crash_gen.py 82.1 KB
Newer Older
S
Steven Li 已提交
1
#-----!/usr/bin/python3.7
S
Steven Li 已提交
2 3 4 5 6 7 8 9 10 11 12 13
###################################################################
#           Copyright (c) 2016 by TAOS Technologies, Inc.
#                     All rights reserved.
#
#  This file is proprietary and confidential to TAOS Technologies.
#  No part of this file may be reproduced, stored, transmitted,
#  disclosed or used in any form or by any means other than as
#  expressly provided by the written permission from Jianhui Tao
#
###################################################################

# -*- coding: utf-8 -*-
14 15
from __future__ import annotations  # For type hinting before definition, ref: https://stackoverflow.com/questions/33533148/how-do-i-specify-that-the-return-type-of-a-method-is-the-same-as-the-class-itsel    

S
Steven Li 已提交
16
import sys
17
import os
18 19
import io
import signal
20
import traceback
21 22 23 24
# Require Python 3
if sys.version_info[0] < 3:
    raise Exception("Must be using Python 3")

S
Steven Li 已提交
25
import getopt
26
import argparse
27
import copy
S
Steven Li 已提交
28
import requests
S
Steven Li 已提交
29 30 31

import threading
import random
32
import time
S
Steven Li 已提交
33
import logging
34
import datetime
35
import textwrap
36 37
import requests
from requests.auth import HTTPBasicAuth
S
Steven Li 已提交
38

39
from typing import List
40
from typing import Dict
41
from typing import Set
42 43
from typing import IO
from queue import Queue, Empty
44

S
Steven Li 已提交
45 46 47 48 49
from util.log import *
from util.dnodes import *
from util.cases import *
from util.sql import *

50
import crash_gen
S
Steven Li 已提交
51 52
import taos

53
# Global variables, tried to keep a small number. 
54 55 56 57

# Command-line/Environment Configurations, will set a bit later
# ConfigNameSpace = argparse.Namespace
gConfig = argparse.Namespace() # Dummy value, will be replaced later
58
logger = None
S
Steven Li 已提交
59

60 61
def runThread(wt: WorkerThread):    
    wt.run()
62

63 64 65 66 67 68 69 70
class CrashGenError(Exception):
    def __init__(self, msg=None, errno=None):
        self.msg = msg    
        self.errno = errno
    
    def __str__(self):
        return self.msg

S
Steven Li 已提交
71
class WorkerThread:
72
    def __init__(self, pool: ThreadPool, tid, 
73 74 75 76
            tc: ThreadCoordinator,
            # te: TaskExecutor,
            ): # note: main thread context!
        # self._curStep = -1 
77
        self._pool = pool
78
        self._tid = tid        
79
        self._tc = tc # type: ThreadCoordinator
S
Steven Li 已提交
80
        # self.threadIdent = threading.get_ident()
81 82
        self._thread = threading.Thread(target=runThread, args=(self,))
        self._stepGate = threading.Event()
S
Steven Li 已提交
83

84
        # Let us have a DB connection of our own
85
        if ( gConfig.per_thread_db_connection ): # type: ignore
86 87
            # print("connector_type = {}".format(gConfig.connector_type))
            self._dbConn = DbConn.createNative() if (gConfig.connector_type == 'native') else DbConn.createRest()
88

89 90
        self._dbInUse = False # if "use db" was executed already

91
    def logDebug(self, msg):
S
Steven Li 已提交
92
        logger.debug("    TRD[{}] {}".format(self._tid, msg))
93 94

    def logInfo(self, msg):
S
Steven Li 已提交
95
        logger.info("    TRD[{}] {}".format(self._tid, msg))
96

97 98 99 100 101 102 103 104
    def dbInUse(self):
        return self._dbInUse

    def useDb(self):
        if ( not self._dbInUse ):
            self.execSql("use db")
        self._dbInUse = True

105 106
    def getTaskExecutor(self):
        return self._tc.getTaskExecutor()     
107

S
Steven Li 已提交
108
    def start(self):
109
        self._thread.start()  # AFTER the thread is recorded
S
Steven Li 已提交
110

111
    def run(self): 
S
Steven Li 已提交
112
        # initialization after thread starts, in the thread context
113
        # self.isSleeping = False
114 115
        logger.info("Starting to run thread: {}".format(self._tid))

116
        if ( gConfig.per_thread_db_connection ): # type: ignore
117
            logger.debug("Worker thread openning database connection")
118
            self._dbConn.open()
S
Steven Li 已提交
119

120 121
        self._doTaskLoop()       
        
122
        # clean up
123
        if ( gConfig.per_thread_db_connection ): # type: ignore 
124
            self._dbConn.close()
125

126 127 128
    def _doTaskLoop(self) :
        # while self._curStep < self._pool.maxSteps:
        # tc = ThreadCoordinator(None)
129 130 131
        while True:  
            tc = self._tc # Thread Coordinator, the overall master            
            tc.crossStepBarrier()  # shared barrier first, INCLUDING the last one
S
Steven Li 已提交
132
            logger.debug("[TRD] Worker thread [{}] exited barrier...".format(self._tid))
133
            self.crossStepGate()   # then per-thread gate, after being tapped
S
Steven Li 已提交
134
            logger.debug("[TRD] Worker thread [{}] exited step gate...".format(self._tid))
135
            if not self._tc.isRunning():
S
Steven Li 已提交
136
                logger.debug("[TRD] Thread Coordinator not running any more, worker thread now stopping...")
137 138
                break

139
            # Fetch a task from the Thread Coordinator
S
Steven Li 已提交
140
            logger.debug("[TRD] Worker thread [{}] about to fetch task".format(self._tid))
141
            task = tc.fetchTask()
142 143

            # Execute such a task
S
Steven Li 已提交
144
            logger.debug("[TRD] Worker thread [{}] about to execute task: {}".format(self._tid, task.__class__.__name__))
145
            task.execute(self)
146
            tc.saveExecutedTask(task)
S
Steven Li 已提交
147
            logger.debug("[TRD] Worker thread [{}] finished executing task".format(self._tid))
148 149

            self._dbInUse = False # there may be changes between steps
150
  
S
Steven Li 已提交
151
    def verifyThreadSelf(self): # ensure we are called by this own thread
152
        if ( threading.get_ident() != self._thread.ident ): 
S
Steven Li 已提交
153 154 155 156 157 158 159
            raise RuntimeError("Unexpectly called from other threads")

    def verifyThreadMain(self): # ensure we are called by the main thread
        if ( threading.get_ident() != threading.main_thread().ident ): 
            raise RuntimeError("Unexpectly called from other threads")

    def verifyThreadAlive(self):
160
        if ( not self._thread.is_alive() ):
S
Steven Li 已提交
161 162
            raise RuntimeError("Unexpected dead thread")

163
    # A gate is different from a barrier in that a thread needs to be "tapped"
S
Steven Li 已提交
164 165 166 167
    def crossStepGate(self):
        self.verifyThreadAlive()
        self.verifyThreadSelf() # only allowed by ourselves
        
168
        # Wait again at the "gate", waiting to be "tapped"
S
Steven Li 已提交
169
        logger.debug("[TRD] Worker thread {} about to cross the step gate".format(self._tid))
170 171
        self._stepGate.wait() 
        self._stepGate.clear()
S
Steven Li 已提交
172
        
173
        # self._curStep += 1  # off to a new step...
S
Steven Li 已提交
174 175 176 177

    def tapStepGate(self): # give it a tap, release the thread waiting there
        self.verifyThreadAlive()
        self.verifyThreadMain() # only allowed for main thread
178
 
S
Steven Li 已提交
179
        logger.debug("[TRD] Tapping worker thread {}".format(self._tid))
180 181
        self._stepGate.set() # wake up!        
        time.sleep(0) # let the released thread run a bit
182

183
    def execSql(self, sql): # TODO: expose DbConn directly
184 185 186
        if ( gConfig.per_thread_db_connection ):
            return self._dbConn.execute(sql)            
        else:
187
            return self._tc.getDbManager().getDbConn().execute(sql)
188

189 190 191 192 193 194 195 196 197 198 199 200
    def querySql(self, sql): # TODO: expose DbConn directly
        if ( gConfig.per_thread_db_connection ):
            return self._dbConn.query(sql)            
        else:
            return self._tc.getDbManager().getDbConn().query(sql)

    def getQueryResult(self):
        if ( gConfig.per_thread_db_connection ):
            return self._dbConn.getQueryResult()       
        else:
            return self._tc.getDbManager().getDbConn().getQueryResult()

201 202 203 204
    def getDbConn(self):
        if ( gConfig.per_thread_db_connection ):
            return self._dbConn     
        else:
205
            return self._tc.getDbManager().getDbConn()
206

207 208 209 210 211
    # def querySql(self, sql): # not "execute", since we are out side the DB context
    #     if ( gConfig.per_thread_db_connection ):
    #         return self._dbConn.query(sql)            
    #     else:
    #         return self._tc.getDbState().getDbConn().query(sql)
212

213
# The coordinator of all worker threads, mostly running in main thread
214
class ThreadCoordinator:
215
    def __init__(self, pool: ThreadPool, dbManager):
216 217
        self._curStep = -1 # first step is 0
        self._pool = pool
218
        # self._wd = wd
219
        self._te = None # prepare for every new step
220
        self._dbManager = dbManager
221 222
        self._executedTasks: List[Task] = [] # in a given step
        self._lock = threading.RLock() # sync access for a few things
S
Steven Li 已提交
223

224
        self._stepBarrier = threading.Barrier(self._pool.numThreads + 1) # one barrier for all threads
225
        self._execStats = ExecutionStats()
226
        self._runStatus = MainExec.STATUS_RUNNING
S
Steven Li 已提交
227

228 229 230
    def getTaskExecutor(self):
        return self._te

231 232
    def getDbManager(self) -> DbManager :
        return self._dbManager
233

234 235 236
    def crossStepBarrier(self):
        self._stepBarrier.wait()

237 238 239 240
    def requestToStop(self):
        self._runStatus = MainExec.STATUS_STOPPING
        self._execStats.registerFailure("User Interruption")

241 242
    def run(self):              
        self._pool.createAndStartThreads(self)
S
Steven Li 已提交
243 244

        # Coordinate all threads step by step
245 246
        self._curStep = -1 # not started yet
        maxSteps = gConfig.max_steps # type: ignore
247
        self._execStats.startExec() # start the stop watch
248 249 250 251 252 253 254
        transitionFailed = False
        hasAbortedTask = False
        while(self._curStep < maxSteps-1 and 
            (not transitionFailed) and 
            (self._runStatus==MainExec.STATUS_RUNNING) and
            (not hasAbortedTask)):  # maxStep==10, last curStep should be 9

S
Steven Li 已提交
255 256 257
            if not gConfig.debug: 
                print(".", end="", flush=True) # print this only if we are not in debug mode
            logger.debug("[TRD] Main thread going to sleep")
258

259
            # Now main thread (that's us) is ready to enter a step
260 261 262 263
            self.crossStepBarrier() # let other threads go past the pool barrier, but wait at the thread gate
            self._stepBarrier.reset() # Other worker threads should now be at the "gate"            

            # At this point, all threads should be pass the overall "barrier" and before the per-thread "gate"
264 265 266 267 268 269 270 271 272 273 274 275 276 277 278 279 280 281 282 283 284 285 286 287 288 289 290 291 292 293 294 295 296 297
            # We use this period to do house keeping work, when all worker threads are QUIET.
            hasAbortedTask = False
            for task in self._executedTasks :
                if task.isAborted() :
                    print("Task aborted: {}".format(task))
                    hasAbortedTask = True
                    break

            if hasAbortedTask : # do transition only if tasks are error free
                self._execStats.registerFailure("Aborted Task Encountered")
            else: 
                try:
                    sm = self._dbManager.getStateMachine()
                    logger.debug("[STT] starting transitions")
                    sm.transition(self._executedTasks) # at end of step, transiton the DB state
                    logger.debug("[STT] transition ended")
                    # Due to limitation (or maybe not) of the Python library, we cannot share connections across threads
                    if sm.hasDatabase() :
                        for t in self._pool.threadList:
                            logger.debug("[DB] use db for all worker threads")
                            t.useDb()
                            # t.execSql("use db") # main thread executing "use db" on behalf of every worker thread
                except taos.error.ProgrammingError as err:
                    if ( err.msg == 'network unavailable' ): # broken DB connection
                        logger.info("DB connection broken, execution failed")
                        traceback.print_stack()
                        transitionFailed = True
                        self._te = None # Not running any more
                        self._execStats.registerFailure("Broken DB Connection")
                        # continue # don't do that, need to tap all threads at end, and maybe signal them to stop
                    else:
                        raise 
            # finally:
            #     pass
298
            
299
            self.resetExecutedTasks() # clear the tasks after we are done
300 301

            # Get ready for next step
S
Steven Li 已提交
302
            logger.debug("<-- Step {} finished".format(self._curStep))
303
            self._curStep += 1 # we are about to get into next step. TODO: race condition here!                
304
            logger.debug("\r\n\n--> Step {} starts with main thread waking up".format(self._curStep)) # Now not all threads had time to go to sleep
305

306
            # A new TE for the new step
307
            if not transitionFailed: # only if not failed
308
                self._te = TaskExecutor(self._curStep)
309

S
Steven Li 已提交
310
            logger.debug("[TRD] Main thread waking up at step {}, tapping worker threads".format(self._curStep)) # Now not all threads had time to go to sleep            
311
            self.tapAllThreads() # Worker threads will wake up at this point, and each execute it's own task
S
Steven Li 已提交
312

313
        logger.debug("Main thread ready to finish up...")
314
        if not transitionFailed: # only in regular situations
315 316 317 318 319 320 321
            self.crossStepBarrier() # Cross it one last time, after all threads finish
            self._stepBarrier.reset()
            logger.debug("Main thread in exclusive zone...")
            self._te = None # No more executor, time to end
            logger.debug("Main thread tapping all threads one last time...")
            self.tapAllThreads() # Let the threads run one last time

322 323
        logger.debug("Main thread joining all threads")
        self._pool.joinAll() # Get all threads to finish
324
        logger.info("\nAll worker threads finished")
325 326
        self._execStats.endExec()

327 328
    def printStats(self):
        self._execStats.printStats()
S
Steven Li 已提交
329

S
Steven Li 已提交
330 331 332 333 334 335
    def isFailed(self):
        return self._execStats.isFailed()

    def getExecStats(self):
        return self._execStats

S
Steven Li 已提交
336 337
    def tapAllThreads(self): # in a deterministic manner
        wakeSeq = []
338
        for i in range(self._pool.numThreads): # generate a random sequence
S
Steven Li 已提交
339 340 341 342
            if Dice.throw(2) == 1 :
                wakeSeq.append(i)
            else:
                wakeSeq.insert(0, i)
343
        logger.debug("[TRD] Main thread waking up worker threads: {}".format(str(wakeSeq)))
344
        # TODO: set dice seed to a deterministic value
S
Steven Li 已提交
345
        for i in wakeSeq:
346
            self._pool.threadList[i].tapStepGate() # TODO: maybe a bit too deep?!
S
Steven Li 已提交
347 348
            time.sleep(0) # yield

349 350 351 352 353 354
    def isRunning(self):
        return self._te != None

    def fetchTask(self) -> Task :
        if ( not self.isRunning() ): # no task
            raise RuntimeError("Cannot fetch task when not running")
355 356
        # return self._wd.pickTask()
        # Alternatively, let's ask the DbState for the appropriate task
357 358 359 360 361 362 363
        # dbState = self.getDbState()
        # tasks = dbState.getTasksAtState() # TODO: create every time?
        # nTasks = len(tasks)
        # i = Dice.throw(nTasks)
        # logger.debug(" (dice:{}/{}) ".format(i, nTasks))
        # # return copy.copy(tasks[i]) # Needs a fresh copy, to save execution results, etc.
        # return tasks[i].clone() # TODO: still necessary?
364
        taskType = self.getDbManager().getStateMachine().pickTaskType() # pick a task type for current state
365
        return taskType(self.getDbManager(), self._execStats) # create a task from it
366 367 368

    def resetExecutedTasks(self):
        self._executedTasks = [] # should be under single thread
369 370 371 372

    def saveExecutedTask(self, task):
        with self._lock:
            self._executedTasks.append(task)
373 374

# We define a class to run a number of threads in locking steps.
375
class ThreadPool:
376
    def __init__(self, numThreads, maxSteps):
377 378 379 380
        self.numThreads = numThreads
        self.maxSteps = maxSteps
        # Internal class variables
        self.curStep = 0
381
        self.threadList = [] # type: List[WorkerThread]
382 383
        
    # starting to run all the threads, in locking steps
384
    def createAndStartThreads(self, tc: ThreadCoordinator):
385
        for tid in range(0, self.numThreads): # Create the threads
386
            workerThread = WorkerThread(self, tid, tc)            
387 388 389 390 391 392 393 394
            self.threadList.append(workerThread)
            workerThread.start() # start, but should block immediately before step 0

    def joinAll(self):
        for workerThread in self.threadList:
            logger.debug("Joining thread...")
            workerThread._thread.join()

395 396
# A queue of continguous POSITIVE integers, used by DbManager to generate continuous numbers
# for new table names
S
Steven Li 已提交
397 398
class LinearQueue():
    def __init__(self):
399
        self.firstIndex = 1  # 1st ever element
S
Steven Li 已提交
400
        self.lastIndex = 0
401
        self._lock = threading.RLock() # our functions may call each other
402
        self.inUse = set() # the indexes that are in use right now
S
Steven Li 已提交
403

404 405 406 407 408 409 410 411 412
    def toText(self):
        return "[{}..{}], in use: {}".format(self.firstIndex, self.lastIndex, self.inUse)

    # Push (add new element, largest) to the tail, and mark it in use
    def push(self): 
        with self._lock:
            # if ( self.isEmpty() ): 
            #     self.lastIndex = self.firstIndex 
            #     return self.firstIndex
413 414
            # Otherwise we have something
            self.lastIndex += 1
415 416
            self.allocate(self.lastIndex)
            # self.inUse.add(self.lastIndex) # mark it in use immediately
417
            return self.lastIndex
S
Steven Li 已提交
418 419

    def pop(self):
420
        with self._lock:
421
            if ( self.isEmpty() ): 
422 423 424
                # raise RuntimeError("Cannot pop an empty queue") 
                return False # TODO: None?
            
425
            index = self.firstIndex
426
            if ( index in self.inUse ):
427 428
                return False

429 430 431 432 433 434 435
            self.firstIndex += 1
            return index

    def isEmpty(self):
        return self.firstIndex > self.lastIndex

    def popIfNotEmpty(self):
436
        with self._lock:
437 438 439 440
            if (self.isEmpty()):
                return 0
            return self.pop()

S
Steven Li 已提交
441
    def allocate(self, i):
442
        with self._lock:
443
            # logger.debug("LQ allocating item {}".format(i))
444 445 446 447
            if ( i in self.inUse ):
                raise RuntimeError("Cannot re-use same index in queue: {}".format(i))
            self.inUse.add(i)

S
Steven Li 已提交
448
    def release(self, i):
449
        with self._lock:
450 451
            # logger.debug("LQ releasing item {}".format(i))
            self.inUse.remove(i) # KeyError possible, TODO: why?
452 453 454 455

    def size(self):
        return self.lastIndex + 1 - self.firstIndex

S
Steven Li 已提交
456
    def pickAndAllocate(self):
457 458 459
        if ( self.isEmpty() ):
            return None
        with self._lock:
460 461 462 463
            cnt = 0 # counting the interations
            while True:
                cnt += 1
                if ( cnt > self.size()*10 ): # 10x iteration already
464 465
                    # raise RuntimeError("Failed to allocate LinearQueue element")
                    return None
466 467
                ret = Dice.throwRange(self.firstIndex, self.lastIndex+1)
                if ( not ret in self.inUse ):
468 469 470 471
                    self.allocate(ret)
                    return ret

class DbConn:
472 473 474 475 476 477 478 479 480 481 482 483 484 485 486 487 488 489 490 491 492
    TYPE_NATIVE = "native-c"
    TYPE_REST = "rest-api"
    TYPE_INVALID = "invalid"

    @classmethod
    def create(cls, connType):
        if connType == cls.TYPE_NATIVE:
            return DbConnNative()
        elif connType == cls.TYPE_REST:
            return DbConnRest()
        else:
            raise RuntimeError("Unexpected connection type: {}".format(connType))

    @classmethod
    def createNative(cls):
        return cls.create(cls.TYPE_NATIVE)

    @classmethod
    def createRest(cls):
        return cls.create(cls.TYPE_REST)

493 494
    def __init__(self):
        self.isOpen = False
495 496 497
        self._type = self.TYPE_INVALID

    def open(self):
498 499 500
        if ( self.isOpen ):
            raise RuntimeError("Cannot re-open an existing DB connection")

501 502
        # below implemented by child classes
        self.openByType()
503

504
        logger.debug("[DB] data connection opened, type = {}".format(self._type))
505 506 507 508 509
        self.isOpen = True

    def resetDb(self): # reset the whole database, etc.
        if ( not self.isOpen ):
            raise RuntimeError("Cannot reset database until connection is open")
510 511
        # self._tdSql.prepare() # Recreate database, etc.

512
        self.execute('drop database if exists db')
513 514
        logger.debug("Resetting DB, dropped database")
        # self._cursor.execute('create database db')
515
        # self._cursor.execute('use db')
516 517
        # tdSql.execute('show databases')

518 519 520 521 522 523 524 525 526 527 528 529 530 531 532 533 534 535 536 537 538 539 540 541 542 543 544 545 546 547 548 549 550 551 552 553 554 555 556 557 558 559 560 561 562 563 564 565 566 567 568 569 570 571 572 573 574 575 576 577 578 579 580 581 582 583 584 585 586 587 588 589 590 591 592 593 594 595 596 597 598 599 600 601 602 603 604 605 606 607 608 609 610 611 612 613 614 615 616 617 618 619 620 621 622 623 624 625 626 627 628
    def queryScalar(self, sql) -> int :
        return self._queryAny(sql)

    def queryString(self, sql) -> str :
        return self._queryAny(sql)

    def _queryAny(self, sql) : # actual query result as an int
        if ( not self.isOpen ):
            raise RuntimeError("Cannot query database until connection is open")
        nRows = self.query(sql)
        if nRows != 1 :
            raise RuntimeError("Unexpected result for query: {}, rows = {}".format(sql, nRows))
        if self.getResultRows() != 1 or self.getResultCols() != 1:
            raise RuntimeError("Unexpected result set for query: {}".format(sql))
        return self.getQueryResult()[0][0]

    def execute(self, sql):
        raise RuntimeError("Unexpected execution, should be overriden")
    def openByType(self):
        raise RuntimeError("Unexpected execution, should be overriden")
    def getQueryResult(self):
        raise RuntimeError("Unexpected execution, should be overriden")
    def getResultRows(self):
        raise RuntimeError("Unexpected execution, should be overriden")
    def getResultCols(self):
        raise RuntimeError("Unexpected execution, should be overriden")

# Sample: curl -u root:taosdata -d "show databases" localhost:6020/rest/sql
class DbConnRest(DbConn):
    def __init__(self):
        super().__init__()
        self._type = self.TYPE_REST
        self._url = "http://localhost:6020/rest/sql" # fixed for now
        self._result = None

    def openByType(self): # Open connection
        pass # do nothing, always open
        
    def close(self):
        if ( not self.isOpen ):
            raise RuntimeError("Cannot clean up database until connection is open")
        # Do nothing for REST
        logger.debug("[DB] REST Database connection closed")
        self.isOpen = False

    def _doSql(self, sql):
        r = requests.post(self._url, 
            data = sql,
            auth = HTTPBasicAuth('root', 'taosdata'))
        rj = r.json()
        # Sanity check for the "Json Result"
        if (not 'status' in rj):
            raise RuntimeError("No status in REST response")

        if rj['status'] == 'error': # clearly reported error
            if (not 'code' in rj): # error without code
                raise RuntimeError("REST error return without code")            
            errno = rj['code'] # May need to massage this in the future
            # print("Raising programming error with REST return: {}".format(rj))
            raise taos.error.ProgrammingError(rj['desc'], errno) # todo: check existance of 'desc'

        if rj['status'] != 'succ': # better be this
            raise RuntimeError("Unexpected REST return status: {}".format(rj['status']))

        nRows = rj['rows'] if ('rows' in rj) else 0
        self._result = rj        
        return nRows

    def execute(self, sql): 
        if ( not self.isOpen ):
            raise RuntimeError("Cannot execute database commands until connection is open")
        logger.debug("[SQL-REST] Executing SQL: {}".format(sql))
        nRows = self._doSql(sql)
        logger.debug("[SQL-REST] Execution Result, nRows = {}, SQL = {}".format(nRows, sql))
        return nRows

    def query(self, sql) :  # return rows affected
        return self.execute(sql)

    def getQueryResult(self):
        return self._result['data']

    def getResultRows(self):
        print(self._result)
        raise RuntimeError("TBD")
        # return self._tdSql.queryRows

    def getResultCols(self):
        print(self._result)
        raise RuntimeError("TBD")
    
class DbConnNative(DbConn):
    def __init__(self):
        super().__init__()
        self._type = self.TYPE_REST
        self._conn = None 
        self._cursor = None
        
    def openByType(self): # Open connection
        cfgPath = "../../build/test/cfg" 
        self._conn = taos.connect(host="127.0.0.1", config=cfgPath) # TODO: make configurable
        self._cursor = self._conn.cursor()

        # Get the connection/cursor ready
        self._cursor.execute('reset query cache')
        # self._cursor.execute('use db') # do this at the beginning of every step

        # Open connection
        self._tdSql = TDSql()
        self._tdSql.init(self._cursor)
        
629 630 631 632
    def close(self):
        if ( not self.isOpen ):
            raise RuntimeError("Cannot clean up database until connection is open")
        self._tdSql.close()
633
        logger.debug("[DB] Database connection closed")
634
        self.isOpen = False
S
Steven Li 已提交
635

636
    def execute(self, sql): 
637
        if ( not self.isOpen ):
638
            raise RuntimeError("Cannot execute database commands until connection is open")
639 640 641 642
        logger.debug("[SQL] Executing SQL: {}".format(sql))
        nRows = self._tdSql.execute(sql)
        logger.debug("[SQL] Execution Result, nRows = {}, SQL = {}".format(nRows, sql))
        return nRows
S
Steven Li 已提交
643

644
    def query(self, sql) :  # return rows affected
645 646
        if ( not self.isOpen ):
            raise RuntimeError("Cannot query database until connection is open")
647 648
        logger.debug("[SQL] Executing SQL: {}".format(sql))
        nRows = self._tdSql.query(sql)
649
        logger.debug("[SQL] Query Result, nRows = {}, SQL = {}".format(nRows, sql))
650
        return nRows
651
        # results are in: return self._tdSql.queryResult
652

653 654 655
    def getQueryResult(self):
        return self._tdSql.queryResult

656 657
    def getResultRows(self):
        return self._tdSql.queryRows
658

659 660
    def getResultCols(self):
        return self._tdSql.queryCols
661 662 663

    
class AnyState:
664
    STATE_INVALID    = -1
665 666 667 668
    STATE_EMPTY      = 0  # nothing there, no even a DB
    STATE_DB_ONLY    = 1  # we have a DB, but nothing else
    STATE_TABLE_ONLY = 2  # we have a table, but totally empty
    STATE_HAS_DATA   = 3  # we have some data in the table
669 670 671 672 673
    _stateNames = ["Invalid", "Empty", "DB_Only", "Table_Only", "Has_Data"]

    STATE_VAL_IDX = 0
    CAN_CREATE_DB = 1
    CAN_DROP_DB = 2
674 675
    CAN_CREATE_FIXED_SUPER_TABLE = 3
    CAN_DROP_FIXED_SUPER_TABLE = 4
676 677 678 679 680 681 682
    CAN_ADD_DATA = 5
    CAN_READ_DATA = 6

    def __init__(self):
        self._info = self.getInfo()

    def __str__(self):
S
Steven Li 已提交
683
        return self._stateNames[self._info[self.STATE_VAL_IDX] + 1] # -1 hack to accomodate the STATE_INVALID case
684 685 686 687

    def getInfo(self):
        raise RuntimeError("Must be overriden by child classes")

S
Steven Li 已提交
688 689 690 691 692 693 694 695
    def equals(self, other):
        if isinstance(other, int):
            return self.getValIndex() == other
        elif isinstance(other, AnyState):
            return self.getValIndex() == other.getValIndex()
        else:
            raise RuntimeError("Unexpected comparison, type = {}".format(type(other)))

696 697 698
    def verifyTasksToState(self, tasks, newState):
        raise RuntimeError("Must be overriden by child classes")

S
Steven Li 已提交
699 700 701
    def getValIndex(self):
        return self._info[self.STATE_VAL_IDX]

702 703 704 705 706 707
    def getValue(self):
        return self._info[self.STATE_VAL_IDX]
    def canCreateDb(self):
        return self._info[self.CAN_CREATE_DB]
    def canDropDb(self):
        return self._info[self.CAN_DROP_DB]
708 709 710 711
    def canCreateFixedSuperTable(self):
        return self._info[self.CAN_CREATE_FIXED_SUPER_TABLE]
    def canDropFixedSuperTable(self):
        return self._info[self.CAN_DROP_FIXED_SUPER_TABLE]
712 713 714 715 716 717 718 719 720 721 722
    def canAddData(self):
        return self._info[self.CAN_ADD_DATA]
    def canReadData(self):
        return self._info[self.CAN_READ_DATA]

    def assertAtMostOneSuccess(self, tasks, cls):
        sCnt = 0
        for task in tasks :
            if not isinstance(task, cls):
                continue
            if task.isSuccess():
S
Steven Li 已提交
723
                # task.logDebug("Task success found")
724 725 726 727 728 729 730 731 732 733 734 735 736 737 738 739 740 741 742 743 744 745 746 747 748 749 750 751 752 753 754 755 756 757 758
                sCnt += 1
                if ( sCnt >= 2 ):
                    raise RuntimeError("Unexpected more than 1 success with task: {}".format(cls))

    def assertIfExistThenSuccess(self, tasks, cls):
        sCnt = 0
        exists = False
        for task in tasks :
            if not isinstance(task, cls):
                continue
            exists = True # we have a valid instance
            if task.isSuccess():
                sCnt += 1
        if ( exists and sCnt <= 0 ):
            raise RuntimeError("Unexpected zero success for task: {}".format(cls))

    def assertNoTask(self, tasks, cls):
        for task in tasks :
            if isinstance(task, cls):
                raise CrashGenError("This task: {}, is not expected to be present, given the success/failure of others".format(cls.__name__))

    def assertNoSuccess(self, tasks, cls):
        for task in tasks :
            if isinstance(task, cls):
                if task.isSuccess():
                    raise RuntimeError("Unexpected successful task: {}".format(cls))

    def hasSuccess(self, tasks, cls):
        for task in tasks :
            if not isinstance(task, cls):
                continue
            if task.isSuccess():
                return True
        return False

S
Steven Li 已提交
759 760 761 762 763 764
    def hasTask(self, tasks, cls):
        for task in tasks :
            if isinstance(task, cls):
                return True
        return False

765 766 767 768 769 770 771 772 773 774 775 776 777 778 779 780 781 782 783 784
class StateInvalid(AnyState):
    def getInfo(self):
        return [
            self.STATE_INVALID,
            False, False, # can create/drop Db
            False, False, # can create/drop fixed table
            False, False, # can insert/read data with fixed table
        ]

    # def verifyTasksToState(self, tasks, newState):

class StateEmpty(AnyState):
    def getInfo(self):
        return [
            self.STATE_EMPTY,
            True, False, # can create/drop Db
            False, False, # can create/drop fixed table
            False, False, # can insert/read data with fixed table
        ]

S
Steven Li 已提交
785
    def verifyTasksToState(self, tasks, newState): 
786 787 788
        if ( self.hasSuccess(tasks, TaskCreateDb) ): # at EMPTY, if there's succes in creating DB
            if ( not self.hasTask(tasks, TaskDropDb) ) : # and no drop_db tasks
                self.assertAtMostOneSuccess(tasks, TaskCreateDb) # we must have at most one. TODO: compare numbers
789 790 791 792 793 794 795 796 797 798 799

class StateDbOnly(AnyState):
    def getInfo(self):
        return [
            self.STATE_DB_ONLY,
            False, True,
            True, False,
            False, False,
        ]

    def verifyTasksToState(self, tasks, newState):
800 801 802
        if ( not self.hasTask(tasks, TaskCreateDb) ):
            self.assertAtMostOneSuccess(tasks, TaskDropDb) # only if we don't create any more
        self.assertIfExistThenSuccess(tasks, TaskDropDb)
S
Steven Li 已提交
803
        # self.assertAtMostOneSuccess(tasks, CreateFixedTableTask) # not true in massively parrallel cases
804
        # Nothing to be said about adding data task
805
        # if ( self.hasSuccess(tasks, DropDbTask) ): # dropped the DB
806
            # self.assertHasTask(tasks, DropDbTask) # implied by hasSuccess
807
            # self.assertAtMostOneSuccess(tasks, DropDbTask)
808
            # self._state = self.STATE_EMPTY
809 810 811 812
        # if ( self.hasSuccess(tasks, TaskCreateSuperTable) ): # did not drop db, create table success
        #     # self.assertHasTask(tasks, CreateFixedTableTask) # tried to create table
        #     if ( not self.hasTask(tasks, TaskDropSuperTable) ): 
        #         self.assertAtMostOneSuccess(tasks, TaskCreateSuperTable) # at most 1 attempt is successful, if we don't drop anything
813
            # self.assertNoTask(tasks, DropDbTask) # should have have tried
814 815 816 817 818 819 820 821 822 823 824 825
            # if ( not self.hasSuccess(tasks, AddFixedDataTask) ): # just created table, no data yet
            #     # can't say there's add-data attempts, since they may all fail
            #     self._state = self.STATE_TABLE_ONLY
            # else:                    
            #     self._state = self.STATE_HAS_DATA
        # What about AddFixedData?
        # elif ( self.hasSuccess(tasks, AddFixedDataTask) ):
        #     self._state = self.STATE_HAS_DATA
        # else: # no success in dropping db tasks, no success in create fixed table? read data should also fail
        #     # raise RuntimeError("Unexpected no-success scenario")   # We might just landed all failure tasks, 
        #     self._state = self.STATE_DB_ONLY  # no change

826
class StateSuperTableOnly(AnyState):
827 828 829 830 831 832 833 834 835
    def getInfo(self):
        return [
            self.STATE_TABLE_ONLY,
            False, True,
            False, True,
            True, True,
        ]

    def verifyTasksToState(self, tasks, newState):
836
        if ( self.hasSuccess(tasks, TaskDropSuperTable) ): # we are able to drop the table
837 838 839
            #self.assertAtMostOneSuccess(tasks, TaskDropSuperTable)
            self.hasSuccess(tasks, TaskCreateSuperTable) # we must have had recreted it

840
            # self._state = self.STATE_DB_ONLY
S
Steven Li 已提交
841 842
        # elif ( self.hasSuccess(tasks, AddFixedDataTask) ): # no success dropping the table, but added data
        #     self.assertNoTask(tasks, DropFixedTableTask) # not true in massively parrallel cases
843
            # self._state = self.STATE_HAS_DATA
S
Steven Li 已提交
844 845 846
        # elif ( self.hasSuccess(tasks, ReadFixedDataTask) ): # no success in prev cases, but was able to read data
            # self.assertNoTask(tasks, DropFixedTableTask)
            # self.assertNoTask(tasks, AddFixedDataTask)
847
            # self._state = self.STATE_TABLE_ONLY # no change
S
Steven Li 已提交
848 849 850
        # else: # did not drop table, did not insert data, did not read successfully, that is impossible
        #     raise RuntimeError("Unexpected no-success scenarios")
        # TODO: need to revamp!!
851 852 853 854 855 856 857 858 859 860 861

class StateHasData(AnyState):
    def getInfo(self):
        return [
            self.STATE_HAS_DATA,
            False, True,
            False, True,
            True, True,
        ]

    def verifyTasksToState(self, tasks, newState):
S
Steven Li 已提交
862
        if ( newState.equals(AnyState.STATE_EMPTY) ):
863 864 865
            self.hasSuccess(tasks, TaskDropDb)
            if ( not self.hasTask(tasks, TaskCreateDb) ) : 
                self.assertAtMostOneSuccess(tasks, TaskDropDb) # TODO: dicy
S
Steven Li 已提交
866
        elif ( newState.equals(AnyState.STATE_DB_ONLY) ): # in DB only
867 868 869
            if ( not self.hasTask(tasks, TaskCreateDb)): # without a create_db task
                self.assertNoTask(tasks, TaskDropDb) # we must have drop_db task
            self.hasSuccess(tasks, TaskDropSuperTable)
870
            # self.assertAtMostOneSuccess(tasks, DropFixedSuperTableTask) # TODO: dicy
871 872 873 874
        # elif ( newState.equals(AnyState.STATE_TABLE_ONLY) ): # data deleted
            # self.assertNoTask(tasks, TaskDropDb)
            # self.assertNoTask(tasks, TaskDropSuperTable)
            # self.assertNoTask(tasks, TaskAddData)
S
Steven Li 已提交
875
            # self.hasSuccess(tasks, DeleteDataTasks)
876
        else: # should be STATE_HAS_DATA
877 878
            if (not self.hasTask(tasks, TaskCreateDb) ): # only if we didn't create one
                self.assertNoTask(tasks, TaskDropDb) # we shouldn't have dropped it
879 880
            if (not self.hasTask(tasks, TaskCreateSuperTable)) :  # if we didn't create the table
                self.assertNoTask(tasks, TaskDropSuperTable) # we should not have a task that drops it            
881
            # self.assertIfExistThenSuccess(tasks, ReadFixedDataTask)
S
Steven Li 已提交
882

883
class StateMechine:
884 885 886 887 888 889 890 891
    def __init__(self, dbConn):
        self._dbConn = dbConn
        self._curState = self._findCurrentState() # starting state
        self._stateWeights = [1,3,5,15] # transitition target probabilities, indexed with value of STATE_EMPTY, STATE_DB_ONLY, etc.
        
    def getCurrentState(self):
        return self._curState

892 893 894
    def hasDatabase(self):
        return self._curState.canDropDb()  # ha, can drop DB means it has one

895 896
    # May be slow, use cautionsly...
    def getTaskTypes(self): # those that can run (directly/indirectly) from the current state
897 898 899 900 901 902
        def typesToStrings(types):
            ss = []
            for t in types:
                ss.append(t.__name__)
            return ss

903 904 905 906 907 908 909 910 911 912 913 914 915 916 917 918 919 920
        allTaskClasses = StateTransitionTask.__subclasses__() # all state transition tasks
        firstTaskTypes = []
        for tc in allTaskClasses:
            # t = tc(self) # create task object            
            if tc.canBeginFrom(self._curState):
                firstTaskTypes.append(tc)
        # now we have all the tasks that can begin directly from the current state, let's figure out the INDIRECT ones
        taskTypes = firstTaskTypes.copy() # have to have these
        for task1 in firstTaskTypes: # each task type gathered so far
            endState = task1.getEndState() # figure the end state
            if endState == None: # does not change end state
                continue # no use, do nothing
            for tc in allTaskClasses: # what task can further begin from there?
                if tc.canBeginFrom(endState) and (tc not in firstTaskTypes):
                    taskTypes.append(tc) # gather it

        if len(taskTypes) <= 0:
            raise RuntimeError("No suitable task types found for state: {}".format(self._curState))   
921
        logger.debug("[OPS] Tasks found for state {}: {}".format(self._curState, typesToStrings(taskTypes)))     
922 923 924 925 926 927 928 929 930
        return taskTypes

    def _findCurrentState(self):
        dbc = self._dbConn
        ts = time.time() # we use this to debug how fast/slow it is to do the various queries to find the current DB state
        if dbc.query("show databases") == 0 : # no database?!
            # logger.debug("Found EMPTY state")
            logger.debug("[STT] empty database found, between {} and {}".format(ts, time.time()))
            return StateEmpty()
931
        dbc.execute("use db") # did not do this when openning connection, and this is NOT the worker thread, which does this on their own
932 933 934 935 936 937 938 939 940 941 942 943 944 945 946
        if dbc.query("show tables") == 0 : # no tables
            # logger.debug("Found DB ONLY state")
            logger.debug("[STT] DB_ONLY found, between {} and {}".format(ts, time.time()))
            return StateDbOnly()
        if dbc.query("SELECT * FROM db.{}".format(DbManager.getFixedSuperTableName()) ) == 0 : # no regular tables
            # logger.debug("Found TABLE_ONLY state")
            logger.debug("[STT] SUPER_TABLE_ONLY found, between {} and {}".format(ts, time.time()))
            return StateSuperTableOnly()
        else: # has actual tables
            # logger.debug("Found HAS_DATA state")
            logger.debug("[STT] HAS_DATA found, between {} and {}".format(ts, time.time()))
            return StateHasData()

    def transition(self, tasks):
        if ( len(tasks) == 0 ): # before 1st step, or otherwise empty
947
            logger.debug("[STT] Starting State: {}".format(self._curState))
948 949 950 951 952 953 954 955 956 957 958 959 960 961 962 963 964 965 966 967 968 969 970 971 972 973 974 975 976 977 978 979 980 981 982 983 984 985 986 987 988 989 990 991 992 993 994 995 996 997 998
            return # do nothing

        self._dbConn.execute("show dnodes") # this should show up in the server log, separating steps

        # Generic Checks, first based on the start state
        if self._curState.canCreateDb():
            self._curState.assertIfExistThenSuccess(tasks, TaskCreateDb)
            # self.assertAtMostOneSuccess(tasks, CreateDbTask) # not really, in case of multiple creation and drops

        if self._curState.canDropDb():
            self._curState.assertIfExistThenSuccess(tasks, TaskDropDb)
            # self.assertAtMostOneSuccess(tasks, DropDbTask) # not really in case of drop-create-drop

        # if self._state.canCreateFixedTable():
            # self.assertIfExistThenSuccess(tasks, CreateFixedTableTask) # Not true, DB may be dropped
            # self.assertAtMostOneSuccess(tasks, CreateFixedTableTask) # not really, in case of create-drop-create

        # if self._state.canDropFixedTable():
            # self.assertIfExistThenSuccess(tasks, DropFixedTableTask) # Not True, the whole DB may be dropped
            # self.assertAtMostOneSuccess(tasks, DropFixedTableTask) # not really in case of drop-create-drop

        # if self._state.canAddData():
        #     self.assertIfExistThenSuccess(tasks, AddFixedDataTask)  # not true actually

        # if self._state.canReadData():
            # Nothing for sure

        newState = self._findCurrentState()
        logger.debug("[STT] New DB state determined: {}".format(newState))
        self._curState.verifyTasksToState(tasks, newState) # can old state move to new state through the tasks?
        self._curState = newState

    def pickTaskType(self):
        taskTypes = self.getTaskTypes() # all the task types we can choose from at curent state
        weights = []
        for tt in taskTypes:
            endState = tt.getEndState()
            if endState != None :
                weights.append(self._stateWeights[endState.getValIndex()]) # TODO: change to a method
            else:
                weights.append(10) # read data task, default to 10: TODO: change to a constant
        i = self._weighted_choice_sub(weights)
        # logger.debug(" (weighted random:{}/{}) ".format(i, len(taskTypes)))        
        return taskTypes[i]

    def _weighted_choice_sub(self, weights): # ref: https://eli.thegreenplace.net/2010/01/22/weighted-random-generation-in-python/
        rnd = random.random() * sum(weights) # TODO: use our dice to ensure it being determinstic?
        for i, w in enumerate(weights):
            rnd -= w
            if rnd < 0:
                return i
999

1000
# Manager of the Database Data/Connection
1001
class DbManager():    
1002
    def __init__(self, resetDb = True):
S
Steven Li 已提交
1003
        self.tableNumQueue = LinearQueue()
1004
        self._lastTick = self.setupLastTick() # datetime.datetime(2019, 1, 1) # initial date time tick
1005 1006
        self._lastInt  = 0 # next one is initial integer 
        self._lock = threading.RLock()
1007
        
1008
        # self.openDbServerConnection()
1009
        self._dbConn = DbConn.createNative() if (gConfig.connector_type=='native') else DbConn.createRest()
1010 1011 1012 1013
        try:
            self._dbConn.open() # may throw taos.error.ProgrammingError: disconnected
        except taos.error.ProgrammingError as err:
            # print("Error type: {}, msg: {}, value: {}".format(type(err), err.msg, err))
1014
            if ( err.msg == 'client disconnected' ): # cannot open DB connection
1015
                print("Cannot establish DB connection, please re-run script without parameter, and follow the instructions.")
S
Steven Li 已提交
1016
                sys.exit(2)
1017 1018 1019
            else:
                raise            
        except:
S
Steven Li 已提交
1020
            print("[=] Unexpected exception")
1021
            raise        
1022 1023 1024

        if resetDb :
            self._dbConn.resetDb() # drop and recreate DB            
1025

1026 1027
        self._stateMachine = StateMechine(self._dbConn) # Do this after dbConn is in proper shape
        
1028 1029 1030
    def getDbConn(self):
        return self._dbConn

1031
    def getStateMachine(self) -> StateMechine :
1032 1033 1034 1035
        return self._stateMachine

    # def getState(self):
    #     return self._stateMachine.getCurrentState()
1036 1037 1038 1039 1040 1041

    # We aim to create a starting time tick, such that, whenever we run our test here once
    # We should be able to safely create 100,000 records, which will not have any repeated time stamp
    # when we re-run the test in 3 minutes (180 seconds), basically we should expand time duration
    # by a factor of 500.
    # TODO: what if it goes beyond 10 years into the future
1042
    # TODO: fix the error as result of above: "tsdb timestamp is out of range"
1043
    def setupLastTick(self):
1044
        t1 = datetime.datetime(2020, 6, 1)
1045
        t2 = datetime.datetime.now()
1046 1047
        elSec = int(t2.timestamp() - t1.timestamp()) # maybe a very large number, takes 69 years to exceed Python int range
        elSec2 = (  elSec % (8 * 12 * 30 * 24 * 60 * 60 / 500 ) ) * 500 # a number representing seconds within 10 years
1048 1049
        # print("elSec = {}".format(elSec))
        t3 = datetime.datetime(2012, 1, 1) # default "keep" is 10 years
1050
        t4 = datetime.datetime.fromtimestamp( t3.timestamp() + elSec2) # see explanation above
1051 1052 1053
        logger.info("Setting up TICKS to start from: {}".format(t4))
        return t4

S
Steven Li 已提交
1054 1055 1056
    def pickAndAllocateTable(self): # pick any table, and "use" it
        return self.tableNumQueue.pickAndAllocate()

1057 1058 1059 1060 1061
    def addTable(self):
        with self._lock:
            tIndex = self.tableNumQueue.push()
        return tIndex

1062 1063
    @classmethod
    def getFixedSuperTableName(cls):
1064
        return "fs_table"
1065

S
Steven Li 已提交
1066 1067 1068
    def releaseTable(self, i): # return the table back, so others can use it
        self.tableNumQueue.release(i)

1069
    def getNextTick(self):
1070
        with self._lock: # prevent duplicate tick
S
Steven Li 已提交
1071 1072 1073 1074 1075
            if Dice.throw(10) == 0 : # 1 in 10 chance
                return self._lastTick + datetime.timedelta(0, -100)
            else: # regular
                self._lastTick += datetime.timedelta(0, 1) # add one second to it
                return self._lastTick
1076 1077

    def getNextInt(self):
1078 1079 1080
        with self._lock:
            self._lastInt += 1
            return self._lastInt
1081 1082

    def getNextBinary(self):
1083
        return "Beijing_Shanghai_Los_Angeles_New_York_San_Francisco_Chicago_Beijing_Shanghai_Los_Angeles_New_York_San_Francisco_Chicago_{}".format(self.getNextInt())
1084 1085 1086

    def getNextFloat(self):
        return 0.9 + self.getNextInt()
1087
    
S
Steven Li 已提交
1088
    def getTableNameToDelete(self):
1089
        tblNum = self.tableNumQueue.pop() # TODO: race condition!
1090 1091 1092
        if ( not tblNum ): # maybe false
            return False
        
S
Steven Li 已提交
1093 1094
        return "table_{}".format(tblNum)

1095 1096 1097
    def cleanUp(self):
        self._dbConn.close()      

1098
class TaskExecutor():
1099 1100 1101 1102 1103 1104 1105 1106 1107 1108 1109 1110 1111 1112 1113 1114 1115 1116 1117 1118 1119 1120 1121 1122 1123 1124 1125 1126 1127 1128 1129 1130 1131 1132 1133 1134 1135
    class BoundedList:
        def __init__(self, size = 10):
            self._size = size
            self._list = []

        def add(self, n: int) :
            if not self._list: # empty
                self._list.append(n)
                return
            # now we should insert
            nItems = len(self._list)
            insPos = 0
            for i in range(nItems):
                insPos = i
                if n <= self._list[i] : # smaller than this item, time to insert
                    break # found the insertion point
                insPos += 1 # insert to the right

            if insPos == 0 : # except for the 1st item, # TODO: elimiate first item as gating item
                return # do nothing

            # print("Inserting at postion {}, value: {}".format(insPos, n))
            self._list.insert(insPos, n) # insert
            
            newLen = len(self._list)
            if newLen <= self._size :
                return # do nothing
            elif newLen == (self._size + 1) :                
                del self._list[0] # remove the first item
            else :
                raise RuntimeError("Corrupt Bounded List")

        def __str__(self):
            return repr(self._list)

    _boundedList = BoundedList()

1136 1137 1138
    def __init__(self, curStep):
        self._curStep = curStep

1139 1140 1141 1142
    @classmethod
    def getBoundedList(cls):
        return cls._boundedList

1143 1144 1145
    def getCurStep(self):
        return self._curStep

1146 1147
    def execute(self, task: Task, wt: WorkerThread): # execute a task on a thread
        task.execute(wt)
1148

1149 1150 1151 1152
    def recordDataMark(self, n: int):
        # print("[{}]".format(n), end="", flush=True)
        self._boundedList.add(n)

1153 1154
    # def logInfo(self, msg):
    #     logger.info("    T[{}.x]: ".format(self._curStep) + msg)
1155

1156 1157
    # def logDebug(self, msg):
    #     logger.debug("    T[{}.x]: ".format(self._curStep) + msg)
1158

S
Steven Li 已提交
1159
class Task():
1160 1161 1162 1163
    taskSn = 100

    @classmethod
    def allocTaskNum(cls):
S
Steven Li 已提交
1164 1165 1166
        Task.taskSn += 1 # IMPORTANT: cannot use cls.taskSn, since each sub class will have a copy
        # logger.debug("Allocating taskSN: {}".format(Task.taskSn))
        return Task.taskSn
1167

1168
    def __init__(self, dbManager: DbManager, execStats: ExecutionStats):        
1169
        self._dbManager = dbManager
1170
        self._workerThread = None 
1171
        self._err = None
1172
        self._aborted = False
1173
        self._curStep = None
1174
        self._numRows = None # Number of rows affected
1175 1176 1177

        # Assign an incremental task serial number        
        self._taskNum = self.allocTaskNum()
S
Steven Li 已提交
1178
        # logger.debug("Creating new task {}...".format(self._taskNum))
1179

1180
        self._execStats = execStats
1181
        self._lastSql = "" # last SQL executed/attempted
1182

1183 1184
    def isSuccess(self):
        return self._err == None
1185

1186 1187 1188
    def isAborted(self):
        return self._aborted

1189
    def clone(self): # TODO: why do we need this again?
1190
        newTask = self.__class__(self._dbManager, self._execStats)
1191 1192 1193
        return newTask

    def logDebug(self, msg):
S
Steven Li 已提交
1194
        self._workerThread.logDebug("Step[{}.{}] {}".format(self._curStep, self._taskNum, msg))
1195 1196

    def logInfo(self, msg):
S
Steven Li 已提交
1197
        self._workerThread.logInfo("Step[{}.{}] {}".format(self._curStep, self._taskNum, msg))
1198

1199
    def _executeInternal(self, te: TaskExecutor, wt: WorkerThread):
1200
        raise RuntimeError("To be implemeted by child classes, class name: {}".format(self.__class__.__name__))
1201

1202 1203
    def execute(self, wt: WorkerThread):
        wt.verifyThreadSelf()
1204
        self._workerThread = wt # type: ignore
1205 1206

        te = wt.getTaskExecutor()
1207 1208
        self._curStep = te.getCurStep()
        self.logDebug("[-] executing task {}...".format(self.__class__.__name__))
1209 1210

        self._err = None
1211
        self._execStats.beginTaskType(self.__class__.__name__) # mark beginning
1212 1213 1214
        try:
            self._executeInternal(te, wt) # TODO: no return value?
        except taos.error.ProgrammingError as err:
1215
            errno2 = err.errno if (err.errno > 0) else 0x80000000 + err.errno # correct error scheme
1216 1217 1218 1219 1220
            if ( errno2 in [
                0x05, # TSDB_CODE_RPC_NOT_READY
                0x200, 0x360, 0x362, 0x36A, 0x36B, 0x36D, 0x381, 0x380, 0x383, 0x503, 
                0x510, # vnode not in ready state
                0x600,
1221 1222
                1000 # REST catch-all error
                ]) : # allowed errors
1223
                self.logDebug("[=] Acceptable Taos library exception: errno=0x{:X}, msg: {}, SQL: {}".format(errno2, err, self._lastSql))
1224
                print("_", end="", flush=True)
1225 1226
                self._err = err  
            else:
1227 1228 1229 1230 1231
                errMsg = "[=] Unexpected Taos library exception: errno=0x{:X}, msg: {}, SQL: {}".format(errno2, err, self._lastSql)
                self.logDebug(errMsg)
                if gConfig.debug :
                    raise # so that we see full stack
                else: # non-debug
1232 1233
                    print("\n\n----------------------------\nProgram ABORTED Due to Unexpected TAOS Error: \n\n{}\n".format(errMsg) +
                        "----------------------------\n")
1234 1235 1236
                    # sys.exit(-1)
                    self._err = err
                    self._aborted = True
S
Steven Li 已提交
1237 1238 1239 1240 1241 1242
        except Exception as e :
            self.logInfo("Non-TAOS exception encountered")
            self._err = e 
            self._aborted = True
            traceback.print_exc()
        except :
1243
            self.logDebug("[=] Unexpected exception, SQL: {}".format(self._lastSql))
1244
            raise
1245
        self._execStats.endTaskType(self.__class__.__name__, self.isSuccess())
1246
        
1247 1248
        self.logDebug("[X] task execution completed, {}, status: {}".format(self.__class__.__name__, "Success" if self.isSuccess() else "Failure"))        
        self._execStats.incExecCount(self.__class__.__name__, self.isSuccess()) # TODO: merge with above.
S
Steven Li 已提交
1249

1250
    def execSql(self, sql):
1251
        self._lastSql = sql
1252
        return self._dbManager.execute(sql)
1253

1254 1255 1256 1257 1258 1259 1260 1261 1262 1263 1264 1265
    def execWtSql(self, wt: WorkerThread, sql): # execute an SQL on the worker thread
        self._lastSql = sql
        return wt.execSql(sql)

    def queryWtSql(self, wt: WorkerThread, sql): # execute an SQL on the worker thread
        self._lastSql = sql
        return wt.querySql(sql)

    def getQueryResult(self, wt: WorkerThread): # execute an SQL on the worker thread
        return wt.getQueryResult()


1266
                  
1267
class ExecutionStats:
1268 1269 1270 1271 1272
    def __init__(self):
        self._execTimes: Dict[str, [int, int]] = {} # total/success times for a task
        self._tasksInProgress = 0
        self._lock = threading.Lock()
        self._firstTaskStartTime = None
1273 1274
        self._execStartTime = None
        self._elapsedTime = 0.0 # total elapsed time
1275 1276
        self._accRunTime = 0.0 # accumulated run time

1277 1278 1279
        self._failed = False
        self._failureReason = None

S
Steven Li 已提交
1280 1281 1282 1283 1284 1285
    def __str__(self):
        return "[ExecStats: _failed={}, _failureReason={}".format(self._failed, self._failureReason)

    def isFailed(self):
        return self._failed == True

1286 1287 1288 1289 1290 1291
    def startExec(self):
        self._execStartTime = time.time()

    def endExec(self):
        self._elapsedTime = time.time() - self._execStartTime

1292 1293 1294 1295 1296 1297 1298 1299 1300 1301 1302 1303 1304 1305 1306 1307 1308 1309 1310 1311 1312
    def incExecCount(self, klassName, isSuccess): # TODO: add a lock here
        if klassName not in self._execTimes:
            self._execTimes[klassName] = [0, 0]
        t = self._execTimes[klassName] # tuple for the data
        t[0] += 1 # index 0 has the "total" execution times
        if isSuccess:
            t[1] += 1 # index 1 has the "success" execution times

    def beginTaskType(self, klassName):
        with self._lock:
            if self._tasksInProgress == 0 : # starting a new round
                self._firstTaskStartTime = time.time() # I am now the first task
            self._tasksInProgress += 1

    def endTaskType(self, klassName, isSuccess):
        with self._lock:
            self._tasksInProgress -= 1
            if self._tasksInProgress == 0 : # all tasks have stopped
                self._accRunTime += (time.time() - self._firstTaskStartTime)
                self._firstTaskStartTime = None

1313 1314 1315 1316
    def registerFailure(self, reason):
        self._failed = True
        self._failureReason = reason

1317
    def printStats(self):
1318 1319 1320 1321
        logger.info("----------------------------------------------------------------------")
        logger.info("| Crash_Gen test {}, with the following stats:".
            format("FAILED (reason: {})".format(self._failureReason) if self._failed else "SUCCEEDED"))
        logger.info("| Task Execution Times (success/total):")
1322 1323
        execTimesAny = 0
        for k, n in self._execTimes.items():            
1324
            execTimesAny += n[0]
1325
            logger.info("|    {0:<24}: {1}/{2}".format(k,n[1],n[0]))
1326
                
1327 1328 1329 1330 1331
        logger.info("| Total Tasks Executed (success or not): {} ".format(execTimesAny))
        logger.info("| Total Tasks In Progress at End: {}".format(self._tasksInProgress))
        logger.info("| Total Task Busy Time (elapsed time when any task is in progress): {:.3f} seconds".format(self._accRunTime))
        logger.info("| Average Per-Task Execution Time: {:.3f} seconds".format(self._accRunTime/execTimesAny))
        logger.info("| Total Elapsed Time (from wall clock): {:.3f} seconds".format(self._elapsedTime))
1332
        logger.info("| Top numbers written: {}".format(TaskExecutor.getBoundedList()))
1333 1334
        logger.info("----------------------------------------------------------------------")
        
1335 1336 1337


class StateTransitionTask(Task):
1338 1339 1340 1341 1342
    LARGE_NUMBER_OF_TABLES = 35
    SMALL_NUMBER_OF_TABLES = 3
    LARGE_NUMBER_OF_RECORDS = 50
    SMALL_NUMBER_OF_RECORDS = 3

1343 1344 1345 1346
    @classmethod
    def getInfo(cls): # each sub class should supply their own information
        raise RuntimeError("Overriding method expected")

1347 1348 1349 1350 1351
    _endState = None 
    @classmethod
    def getEndState(cls): # TODO: optimize by calling it fewer times
        raise RuntimeError("Overriding method expected")

1352 1353 1354
    # @classmethod
    # def getBeginStates(cls):
    #     return cls.getInfo()[0]
1355

1356 1357 1358
    # @classmethod
    # def getEndState(cls): # returning the class name
    #     return cls.getInfo()[0]
1359 1360

    @classmethod
1361 1362 1363
    def canBeginFrom(cls, state: AnyState):
        # return state.getValue() in cls.getBeginStates()
        raise RuntimeError("must be overriden")
1364

1365 1366 1367 1368
    @classmethod
    def getRegTableName(cls, i):
        return "db.reg_table_{}".format(i)

1369 1370 1371
    def execute(self, wt: WorkerThread):
        super().execute(wt)
        
1372
class TaskCreateDb(StateTransitionTask):
1373
    @classmethod
1374 1375
    def getEndState(cls):
        return StateDbOnly() 
1376

1377 1378 1379 1380
    @classmethod
    def canBeginFrom(cls, state: AnyState):
        return state.canCreateDb()

1381
    def _executeInternal(self, te: TaskExecutor, wt: WorkerThread):
1382
        self.execWtSql(wt, "create database db")       
1383

1384
class TaskDropDb(StateTransitionTask):
1385
    @classmethod
1386 1387
    def getEndState(cls):
        return StateEmpty()
1388

1389 1390 1391 1392
    @classmethod
    def canBeginFrom(cls, state: AnyState):
        return state.canDropDb()

1393
    def _executeInternal(self, te: TaskExecutor, wt: WorkerThread):
1394
        self.execWtSql(wt, "drop database db")
S
Steven Li 已提交
1395
        logger.debug("[OPS] database dropped at {}".format(time.time()))
1396

1397
class TaskCreateSuperTable(StateTransitionTask):
1398
    @classmethod
1399 1400
    def getEndState(cls):
        return StateSuperTableOnly()
1401

1402 1403
    @classmethod
    def canBeginFrom(cls, state: AnyState):
1404
        return state.canCreateFixedSuperTable()
1405

1406
    def _executeInternal(self, te: TaskExecutor, wt: WorkerThread):
1407 1408 1409 1410 1411 1412 1413
        if not wt.dbInUse(): # no DB yet, to the best of our knowledge
            logger.debug("Skipping task, no DB yet")
            return

        tblName = self._dbManager.getFixedSuperTableName()   
        # wt.execSql("use db")    # should always be in place
        self.execWtSql(wt, "create table db.{} (ts timestamp, speed int) tags (b binary(200), f float) ".format(tblName))
1414 1415
        # No need to create the regular tables, INSERT will do that automatically

S
Steven Li 已提交
1416

1417
class TaskReadData(StateTransitionTask):
1418
    @classmethod
1419 1420
    def getEndState(cls):
        return None # meaning doesn't affect state
1421

1422 1423 1424 1425
    @classmethod
    def canBeginFrom(cls, state: AnyState):
        return state.canReadData()

1426
    def _executeInternal(self, te: TaskExecutor, wt: WorkerThread):
1427
        sTbName = self._dbManager.getFixedSuperTableName()        
1428 1429
        self.queryWtSql(wt, "select TBNAME from db.{}".format(sTbName)) # TODO: analyze result set later

1430
        if random.randrange(5) == 0 : # 1 in 5 chance, simulate a broken connection. TODO: break connection in all situations
1431 1432
            wt.getDbConn().close()
            wt.getDbConn().open()
1433
        else:
1434
            rTables = self.getQueryResult(wt) # wt.getDbConn().getQueryResult()
1435 1436
            # print("rTables[0] = {}, type = {}".format(rTables[0], type(rTables[0])))
            for rTbName in rTables : # regular tables
1437
                self.execWtSql(wt, "select * from db.{}".format(rTbName[0]))
1438

1439 1440
        # tdSql.query(" cars where tbname in ('carzero', 'carone')")

1441
class TaskDropSuperTable(StateTransitionTask):
1442
    @classmethod
1443 1444
    def getEndState(cls):
        return StateDbOnly() 
1445

1446 1447
    @classmethod
    def canBeginFrom(cls, state: AnyState):
1448
        return state.canDropFixedSuperTable()
1449

1450
    def _executeInternal(self, te: TaskExecutor, wt: WorkerThread):
1451 1452 1453 1454 1455
        # 1/2 chance, we'll drop the regular tables one by one, in a randomized sequence
        if Dice.throw(2) == 0 : 
            tblSeq = list(range(2 + (self.LARGE_NUMBER_OF_TABLES if gConfig.larger_data else self.SMALL_NUMBER_OF_TABLES))) 
            random.shuffle(tblSeq) 
            tickOutput = False # if we have spitted out a "d" character for "drop regular table"
1456
            isSuccess = True
1457
            for i in tblSeq: 
1458
                regTableName = self.getRegTableName(i); # "db.reg_table_{}".format(i)                  
1459
                try:
1460
                    self.execWtSql(wt, "drop table {}".format(regTableName)) # nRows always 0, like MySQL
1461 1462
                except taos.error.ProgrammingError as err:                    
                    errno2 = err.errno if (err.errno > 0) else 0x80000000 + err.errno # correcting for strange error number scheme
1463 1464
                    if ( errno2 in [0x362]) : # mnode invalid table name
                        isSuccess = False
1465
                        logger.debug("[DB] Acceptable error when dropping a table")
1466
                    continue # try to delete next regular table
1467 1468 1469

                if (not tickOutput):
                    tickOutput = True # Print only one time
1470
                    if isSuccess :
1471 1472
                        print("d", end="", flush=True)
                    else:
1473
                        print("f", end="", flush=True)                    
1474 1475 1476 1477

        # Drop the super table itself
        tblName = self._dbManager.getFixedSuperTableName()     
        self.execWtSql(wt, "drop table db.{}".format(tblName))
1478

1479 1480 1481 1482 1483 1484 1485 1486 1487 1488 1489 1490 1491
class TaskAlterTags(StateTransitionTask):
    @classmethod
    def getEndState(cls):
        return None # meaning doesn't affect state

    @classmethod
    def canBeginFrom(cls, state: AnyState):
        return state.canDropFixedSuperTable() # if we can drop it, we can alter tags

    def _executeInternal(self, te: TaskExecutor, wt: WorkerThread):
        tblName = self._dbManager.getFixedSuperTableName()   
        dice = Dice.throw(4)
        if dice == 0 :
1492
            sql = "alter table db.{} add tag extraTag int".format(tblName)
1493
        elif dice == 1 :
1494
            sql = "alter table db.{} drop tag extraTag".format(tblName)
1495
        elif dice == 2 :
1496
            sql = "alter table db.{} drop tag newTag".format(tblName)
1497
        else: # dice == 3
1498 1499 1500
            sql = "alter table db.{} change tag extraTag newTag".format(tblName)

        self.execWtSql(wt, sql)
1501

1502
class TaskAddData(StateTransitionTask):
1503
    activeTable : Set[int] = set() # Track which table is being actively worked on
1504 1505 1506 1507 1508 1509 1510 1511 1512 1513 1514 1515 1516 1517

    # We use these two files to record operations to DB, useful for power-off tests
    fAddLogReady = None
    fAddLogDone = None

    @classmethod
    def prepToRecordOps(cls):
        if gConfig.record_ops :            
            if ( cls.fAddLogReady == None ):
                logger.info("Recording in a file operations to be performed...")
                cls.fAddLogReady = open("add_log_ready.txt", "w")
            if ( cls.fAddLogDone == None ):
                logger.info("Recording in a file operations completed...")
                cls.fAddLogDone = open("add_log_done.txt", "w")
1518

1519
    @classmethod
1520 1521
    def getEndState(cls):
        return StateHasData()
1522 1523 1524 1525

    @classmethod
    def canBeginFrom(cls, state: AnyState):
        return state.canAddData()
1526 1527
        
    def _executeInternal(self, te: TaskExecutor, wt: WorkerThread):
1528
        ds = self._dbManager
1529
        # wt.execSql("use db") # TODO: seems to be an INSERT bug to require this
1530
        tblSeq = list(range(self.LARGE_NUMBER_OF_TABLES if gConfig.larger_data else self.SMALL_NUMBER_OF_TABLES)) 
1531 1532 1533 1534 1535 1536 1537 1538
        random.shuffle(tblSeq) 
        for i in tblSeq: 
            if ( i in self.activeTable ): # wow already active
                # logger.info("Concurrent data insertion into table: {}".format(i))      
                # print("ct({})".format(i), end="", flush=True) # Concurrent insertion into table
                print("x", end="", flush=True)
            else:
                self.activeTable.add(i) # marking it active
1539 1540
            # No need to shuffle data sequence, unless later we decide to do non-increment insertion      
            regTableName = self.getRegTableName(i); # "db.reg_table_{}".format(i)      
1541
            for j in range(self.LARGE_NUMBER_OF_RECORDS if gConfig.larger_data else self.SMALL_NUMBER_OF_RECORDS) : # number of records per table
1542
                nextInt = ds.getNextInt()                
1543 1544 1545 1546 1547 1548 1549
                if gConfig.record_ops:
                    self.prepToRecordOps()
                    self.fAddLogReady.write("Ready to write {} to {}\n".format(nextInt, regTableName))
                    self.fAddLogReady.flush()
                    os.fsync(self.fAddLogReady)
                sql = "insert into {} using {} tags ('{}', {}) values ('{}', {});".format(
                    regTableName, 
1550 1551
                    ds.getFixedSuperTableName(), 
                    ds.getNextBinary(), ds.getNextFloat(),
1552
                    ds.getNextTick(), nextInt)
1553
                self.execWtSql(wt, sql) 
1554 1555
                # Successfully wrote the data into the DB, let's record it somehow
                te.recordDataMark(nextInt)
1556 1557 1558 1559
                if gConfig.record_ops:
                    self.fAddLogDone.write("Wrote {} to {}\n".format(nextInt, regTableName))
                    self.fAddLogDone.flush()
                    os.fsync(self.fAddLogDone)
1560
            self.activeTable.discard(i) # not raising an error, unlike remove
1561 1562


S
Steven Li 已提交
1563 1564 1565 1566 1567 1568 1569 1570 1571 1572 1573 1574 1575 1576 1577 1578 1579 1580 1581 1582 1583 1584
# Deterministic random number generator
class Dice():
    seeded = False # static, uninitialized

    @classmethod
    def seed(cls, s): # static
        if (cls.seeded):
            raise RuntimeError("Cannot seed the random generator more than once")
        cls.verifyRNG()
        random.seed(s)
        cls.seeded = True  # TODO: protect against multi-threading

    @classmethod
    def verifyRNG(cls): # Verify that the RNG is determinstic
        random.seed(0)
        x1 = random.randrange(0, 1000)
        x2 = random.randrange(0, 1000)
        x3 = random.randrange(0, 1000)
        if ( x1 != 864 or x2!=394 or x3!=776 ):
            raise RuntimeError("System RNG is not deterministic")

    @classmethod
1585 1586
    def throw(cls, stop): # get 0 to stop-1
        return cls.throwRange(0, stop)
S
Steven Li 已提交
1587 1588

    @classmethod
1589
    def throwRange(cls, start, stop): # up to stop-1
S
Steven Li 已提交
1590 1591
        if ( not cls.seeded ):
            raise RuntimeError("Cannot throw dice before seeding it")
1592
        return random.randrange(start, stop)
S
Steven Li 已提交
1593 1594 1595


# Anyone needing to carry out work should simply come here
1596 1597 1598 1599 1600 1601 1602 1603 1604 1605 1606 1607 1608 1609 1610 1611 1612 1613 1614 1615 1616 1617
# class WorkDispatcher():
#     def __init__(self, dbState):
#         # self.totalNumMethods = 2
#         self.tasks = [
#             # CreateTableTask(dbState), # Obsolete
#             # DropTableTask(dbState),
#             # AddDataTask(dbState),
#         ]

#     def throwDice(self):
#         max = len(self.tasks) - 1 
#         dRes = random.randint(0, max)
#         # logger.debug("Threw the dice in range [{},{}], and got: {}".format(0,max,dRes))
#         return dRes

#     def pickTask(self):
#         dice = self.throwDice()
#         return self.tasks[dice]

#     def doWork(self, workerThread):
#         task = self.pickTask()
#         task.execute(workerThread)
S
Steven Li 已提交
1618

S
Steven Li 已提交
1619 1620
class LoggingFilter(logging.Filter):
    def filter(self, record: logging.LogRecord):
S
Steven Li 已提交
1621 1622 1623
        if ( record.levelno >= logging.INFO ) :
            return True # info or above always log

S
Steven Li 已提交
1624 1625 1626 1627
        # Commenting out below to adjust...

        # if msg.startswith("[TRD]"):
        #     return False
S
Steven Li 已提交
1628 1629
        return True

1630 1631 1632 1633 1634
class MyLoggingAdapter(logging.LoggerAdapter):    
    def process(self, msg, kwargs):
        return "[{}]{}".format(threading.get_ident() % 10000, msg), kwargs
        # return '[%s] %s' % (self.extra['connid'], msg), kwargs

1635
class SvcManager:    
1636
    MAX_QUEUE_SIZE = 10000
1637

1638 1639 1640 1641
    def __init__(self):
        print("Starting service manager")
        signal.signal(signal.SIGTERM, self.sigIntHandler)
        signal.signal(signal.SIGINT, self.sigIntHandler)
1642
        signal.signal(signal.SIGUSR1, self.sigUsrHandler)
1643 1644 1645
        self.ioThread = None
        self.subProcess = None
        self.shouldStop = False
1646
        # self.status = MainExec.STATUS_RUNNING # set inside _startTaosService()
1647 1648

    def svcOutputReader(self, out: IO, queue):
1649 1650 1651 1652
        # 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 : 
        for line in iter(out.readline, b''):
1653
            # print("Finished reading a line: {}".format(line))
1654 1655 1656 1657 1658 1659 1660 1661 1662 1663 1664 1665 1666 1667 1668 1669 1670 1671 1672 1673
            # print("Adding item to queue...")
            line = line.decode("utf-8").rstrip()
            queue.put(line) # This might block, and then causing "out" buffer to block            
            print("_i", end="", flush=True) 

            # Trim the queue if necessary
            oneTenthQSize = self.MAX_QUEUE_SIZE // 10
            if (queue.qsize() >= (self.MAX_QUEUE_SIZE - oneTenthQSize) ) : # 90% full?
                print("Triming IPC queue by: {}".format(oneTenthQSize))
                for i in range(0, oneTenthQSize) :
                    try:
                        queue.get_nowait()
                    except Empty:
                        break # break out of for loop, no more trimming    

            if self.shouldStop :
                print("Stopping to read output from sub process")
                break

            # queue.put(line)
1674
        print("\nNo more output (most likely) from IO thread managing TDengine service") # meaning sub process must have died
1675 1676
        out.close()

1677 1678 1679 1680 1681 1682 1683 1684 1685 1686 1687 1688 1689 1690 1691 1692 1693 1694 1695 1696 1697
    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")
1698
        if self.status != MainExec.STATUS_RUNNING :
1699
            print("Ignoring repeated SIG...")
1700
            return # do nothing if it's already not running
1701 1702 1703 1704 1705 1706
        self.status = MainExec.STATUS_STOPPING

        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" :
1707
            self.stopTaosService()
1708
        elif choice == "3" :
1709 1710
            self.stopTaosService()
            self.startTaosService()
1711 1712
        else:
            raise RuntimeError("Invalid menu choice: {}".format(choice))
1713

1714 1715 1716 1717 1718 1719 1720
    def sigIntHandler(self, signalNumber, frame):
        print("Sig INT Handler starting...")
        if self.status != MainExec.STATUS_RUNNING :
            print("Ignoring repeated SIG_INT...")
            return

        self.status = MainExec.STATUS_STOPPING # immediately set our status    
1721
        self.stopTaosService()  
1722 1723 1724 1725 1726
        print("INT signal handler returning...")  

    def sigHandlerResume(self) :
        print("Resuming TDengine service manager thread (main thread)...\n\n")
        self.status = MainExec.STATUS_RUNNING
1727 1728 1729 1730

    def joinIoThread(self):
        if self.ioThread :
            self.ioThread.join()
1731 1732 1733 1734
            self.ioThread = None   
        else :
            print("Joining empty thread, doing nothing")
    
1735
    TD_READY_MSG = "TDengine is initialized successfully"
1736 1737 1738 1739 1740
    def _procIpcBatch(self):
        # Process all the output generated by the underlying sub process, managed by IO thread
        while True :
            try:  
                line = self.ipcQueue.get_nowait() # getting output at fast speed   
1741 1742 1743 1744 1745
                print("_o", end="", flush=True)   
                if self.status == MainExec.STATUS_STARTING : # we are starting, let's see if we have started
                    if line.find(self.TD_READY_MSG) != -1 : # found
                        self.status = MainExec.STATUS_RUNNING
                              
1746 1747 1748 1749 1750 1751
            except Empty:
                # time.sleep(2.3) # wait only if there's no output
                # no more output
                return # we are done with THIS BATCH
            else: # got line
                print(line)
1752

1753 1754
    def _procIpcAll(self):
        while True :
1755
            print("<", end="", flush=True)
1756 1757 1758 1759 1760
            self._procIpcBatch() # process one batch

            # check if the ioThread is still running
            if (not self.ioThread) or (not self.ioThread.is_alive()):
                print("IO Thread (with subprocess) has ended, main thread now exiting...")
1761
                self.stopTaosService()
1762 1763 1764 1765 1766 1767 1768 1769 1770
                self._procIpcBatch() # one more batch
                return # TODO: maybe one last batch?

            # Maybe handler says we should exit now
            if self.shouldStop:
                print("Main thread ending all IPC processing with IOThread/SubProcess")
                self._procIpcBatch() # one more batch
                return

1771
            print(">", end="", flush=True)
1772 1773
            time.sleep(0.5)

1774
    def startTaosService(self):
1775 1776 1777
        ON_POSIX = 'posix' in sys.builtin_module_names
        svcCmd = ['../../build/build/bin/taosd', '-c', '../../build/test/cfg']
        # svcCmd = ['vmstat', '1']
1778 1779 1780 1781 1782 1783 1784 1785 1786 1787 1788 1789 1790
        if self.subProcess : # already there
            raise RuntimeError("Corrupt process state")

        self.subProcess = subprocess.Popen(
            svcCmd, 
            stdout=subprocess.PIPE, 
            # bufsize=1, # not supported in binary mode
            close_fds=ON_POSIX) # had text=True, which interferred with reading EOF
        self.ipcQueue = Queue()

        if self.ioThread :
            raise RuntimeError("Corrupt thread state")
        self.ioThread = threading.Thread(target=self.svcOutputReader, args=(self.subProcess.stdout, self.ipcQueue))
1791 1792 1793
        self.ioThread.daemon = True # thread dies with the program
        self.ioThread.start()

1794
        self.shouldStop = False # don't let the main loop stop
1795 1796 1797 1798 1799 1800 1801 1802 1803 1804 1805 1806 1807
        self.status = MainExec.STATUS_STARTING

        # wait for service to start
        for i in range(0, 10) :
            time.sleep(1.0)
            self._procIpcBatch() # pump messages
            print("_zz_", end="", flush=True)
            if self.status == MainExec.STATUS_RUNNING :
                print("TDengine service READY to process requests")
                return # now we've started
        raise RuntimeError("TDengine service did not start successfully") # TODO: handle this better?

    def stopTaosService(self):
1808 1809 1810 1811 1812 1813 1814 1815 1816 1817 1818 1819 1820 1821 1822 1823 1824 1825
        # can be called from both main thread or signal handler
        print("Terminating TDengine service running as the sub process...")
        # 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.subProcess :
            print("Process already stopped")
            return

        retCode = self.subProcess.poll()
        if retCode : # valid return code, process ended
            self.subProcess = None
        else: # process still alive, let's interrupt it
            print("Sub process still running, sending SIG_INT and waiting for it to stop...")
            self.subProcess.send_signal(signal.SIGINT) # sub process should end, then IPC queue should end, causing IO thread to end
            try :
                self.subProcess.wait(10)
            except subprocess.TimeoutExpired as err:
                print("Time out waiting for TDengine service process to exit")
            else:
1826
                print("TDengine service process terminated successfully from SIG_INT")
1827 1828 1829 1830 1831 1832 1833 1834 1835
                self.subProcess = None

        if self.subProcess and (not self.subProcess.poll()):
            print("Sub process is still running... pid = {}".format(self.subProcess.pid))
            
        self.shouldStop = True
        self.joinIoThread()

    def run(self):
1836
        self.startTaosService()
1837

1838 1839 1840 1841 1842 1843
        # proc = subprocess.Popen(['echo', '"to stdout"'], 
        #                 stdout=subprocess.PIPE,
        #                 )
        # stdout_value = proc.communicate()[0]
        # print('\tstdout: {}'.format(repr(stdout_value)))

1844
        self._procIpcAll()
1845

1846 1847 1848
        print("End of loop reading from IPC queue")
        self.joinIoThread() # should have started already
        print("SvcManager Run Finished")
1849 1850 1851 1852 1853 1854 1855 1856 1857 1858 1859 1860 1861 1862 1863 1864 1865 1866 1867 1868 1869 1870 1871 1872

class ClientManager:
    def __init__(self):
        print("Starting service manager")
        signal.signal(signal.SIGTERM, self.sigIntHandler)
        signal.signal(signal.SIGINT, self.sigIntHandler)

        self.status = MainExec.STATUS_RUNNING
        self.tc = None

    def sigIntHandler(self, signalNumber, frame):
        if self.status != MainExec.STATUS_RUNNING :
            print("Ignoring repeated SIGINT...")
            return # do nothing if it's already not running
        self.status = MainExec.STATUS_STOPPING # immediately set our status

        print("Terminating program...")
        self.tc.requestToStop()

    def _printLastNumbers(self): # to verify data durability
        dbManager = DbManager(resetDb=False)
        dbc = dbManager.getDbConn()
        if dbc.query("show databases") == 0 : # no databae
            return
S
Steven Li 已提交
1873 1874
        if dbc.query("show tables") == 0 : # no tables
            return
1875 1876 1877 1878 1879 1880 1881 1882 1883 1884 1885 1886 1887 1888 1889 1890 1891 1892 1893 1894 1895 1896 1897 1898 1899

        dbc.execute("use db")
        sTbName = dbManager.getFixedSuperTableName()        

        # get all regular tables
        dbc.query("select TBNAME from db.{}".format(sTbName)) # TODO: analyze result set later
        rTables = dbc.getQueryResult()

        bList = TaskExecutor.BoundedList()
        for rTbName in rTables : # regular tables
            dbc.query("select speed from db.{}".format(rTbName[0]))
            numbers = dbc.getQueryResult()
            for row in numbers :                
                # print("<{}>".format(n), end="", flush=True)
                bList.add(row[0])

        print("Top numbers in DB right now: {}".format(bList))
        print("TDengine client execution is about to start in 2 seconds...")
        time.sleep(2.0)
        dbManager = None # release?

    def prepare(self):
        self._printLastNumbers()

    def run(self):
1900 1901 1902 1903
        if gConfig.auto_start_service :
            svcMgr = SvcManager()
            svcMgr.startTaosService()

1904 1905
        self._printLastNumbers()

1906 1907 1908
        dbManager = DbManager() # Regular function
        Dice.seed(0) # initial seeding of dice
        thPool = ThreadPool(gConfig.num_threads, gConfig.max_steps)
1909
        self.tc = ThreadCoordinator(thPool, dbManager)
S
Steven Li 已提交
1910
        
1911
        self.tc.run()
S
Steven Li 已提交
1912 1913
        # print("exec stats: {}".format(self.tc.getExecStats()))
        # print("TC failed = {}".format(self.tc.isFailed()))
1914
        self.conclude()
1915 1916
        if gConfig.auto_start_service :
            svcMgr.stopTaosService()
S
Steven Li 已提交
1917 1918
        # print("TC failed (2) = {}".format(self.tc.isFailed()))
        return 1 if self.tc.isFailed() else 0 # Linux return code: ref https://shapeshed.com/unix-exit-codes/
1919 1920 1921 1922 1923 1924 1925

    def conclude(self):
        self.tc.printStats()
        self.tc.getDbManager().cleanUp()    


class MainExec:
1926 1927 1928
    STATUS_STARTING = 1
    STATUS_RUNNING = 2
    STATUS_STOPPING = 3
1929 1930 1931 1932 1933
    # STATUS_STOPPED = 3 # Not used yet

    @classmethod
    def runClient(cls):
        clientManager = ClientManager()
S
Steven Li 已提交
1934
        return clientManager.run()
1935 1936 1937

    @classmethod
    def runService(cls):
1938 1939
        svcManager = SvcManager()
        svcManager.run()
1940 1941 1942 1943 1944 1945 1946 1947 1948 1949 1950 1951 1952 1953 1954 1955 1956 1957 1958 1959 1960 1961 1962 1963 1964 1965 1966 1967 1968 1969 1970 1971 1972 1973 1974 1975 1976 1977 1978 1979 1980 1981 1982 1983

    @classmethod
    def runTemp(cls): # for debugging purposes
        # # Hack to exercise reading from disk, imcreasing coverage. TODO: fix
        # dbc = dbState.getDbConn()
        # sTbName = dbState.getFixedSuperTableName()   
        # dbc.execute("create database if not exists db")
        # if not dbState.getState().equals(StateEmpty()):
        #     dbc.execute("use db")     

        # rTables = None
        # try: # the super table may not exist
        #     sql = "select TBNAME from db.{}".format(sTbName)
        #     logger.info("Finding out tables in super table: {}".format(sql))
        #     dbc.query(sql) # TODO: analyze result set later
        #     logger.info("Fetching result")
        #     rTables = dbc.getQueryResult()
        #     logger.info("Result: {}".format(rTables))
        # except taos.error.ProgrammingError as err:
        #     logger.info("Initial Super table OPS error: {}".format(err))
        
        # # sys.exit()
        # if ( not rTables == None):
        #     # print("rTables[0] = {}, type = {}".format(rTables[0], type(rTables[0])))
        #     try:
        #         for rTbName in rTables : # regular tables
        #             ds = dbState
        #             logger.info("Inserting into table: {}".format(rTbName[0]))
        #             sql = "insert into db.{} values ('{}', {});".format(
        #                 rTbName[0],                    
        #                 ds.getNextTick(), ds.getNextInt())
        #             dbc.execute(sql)
        #         for rTbName in rTables : # regular tables        
        #             dbc.query("select * from db.{}".format(rTbName[0])) # TODO: check success failure
        #         logger.info("Initial READING operation is successful")       
        #     except taos.error.ProgrammingError as err:
        #         logger.info("Initial WRITE/READ error: {}".format(err))   
        
        # Sandbox testing code
        # dbc = dbState.getDbConn()
        # while True:
        #     rows = dbc.query("show databases") 
        #     print("Rows: {}, time={}".format(rows, time.time()))
        return 
S
Steven Li 已提交
1984

1985
def main():
1986
    # Super cool Python argument library: https://docs.python.org/3/library/argparse.html
1987 1988 1989 1990 1991 1992 1993 1994 1995
    parser = argparse.ArgumentParser(
        formatter_class=argparse.RawDescriptionHelpFormatter,
        description=textwrap.dedent('''\
            TDengine Auto Crash Generator (PLEASE NOTICE the Prerequisites Below)
            ---------------------------------------------------------------------
            1. You build TDengine in the top level ./build directory, as described in offical docs
            2. You run the server there before this script: ./build/bin/taosd -c test/cfg

            '''))
1996

1997 1998
    parser.add_argument('-a', '--auto-start-service', action='store_true',                        
                        help='Automatically start/stop the TDengine service (default: false)')
1999 2000
    parser.add_argument('-c', '--connector-type', action='store', default='native', type=str,
                        help='Connector type to use: native, rest, or mixed (default: 10)')
2001 2002
    parser.add_argument('-d', '--debug', action='store_true',                        
                        help='Turn on DEBUG mode for more logging (default: false)')
2003 2004
    parser.add_argument('-e', '--run-tdengine', action='store_true',                        
                        help='Run TDengine service in foreground (default: false)')
2005 2006
    parser.add_argument('-l', '--larger-data', action='store_true',                        
                        help='Write larger amount of data during write operations (default: false)')
2007
    parser.add_argument('-p', '--per-thread-db-connection', action='store_true',                        
2008
                        help='Use a single shared db connection (default: false)')
2009 2010
    parser.add_argument('-r', '--record-ops', action='store_true',                        
                        help='Use a pair of always-fsynced fils to record operations performing + performed, for power-off tests (default: false)')                    
2011
    parser.add_argument('-s', '--max-steps', action='store', default=1000, type=int,
2012
                        help='Maximum number of steps to run (default: 100)')
2013
    parser.add_argument('-t', '--num-threads', action='store', default=5, type=int,
2014
                        help='Number of threads to run (default: 10)')
2015

2016
    global gConfig
2017
    gConfig = parser.parse_args()
2018

2019 2020 2021
    # if len(sys.argv) == 1:
    #     parser.print_help()
    #     sys.exit()
2022
 
2023
    # Logging Stuff
2024
    global logger
2025 2026 2027 2028 2029 2030 2031
    _logger = logging.getLogger('CrashGen') # real logger
    _logger.addFilter(LoggingFilter()) 
    ch = logging.StreamHandler()
    _logger.addHandler(ch)

    logger = MyLoggingAdapter(_logger, []) # Logging adapter, to be used as a logger

2032 2033
    if ( gConfig.debug ):
        logger.setLevel(logging.DEBUG) # default seems to be INFO        
S
Steven Li 已提交
2034 2035
    else:
        logger.setLevel(logging.INFO)
2036 2037
    
    # Run server or client
2038 2039 2040
    if gConfig.run_tdengine : # run server
        MainExec.runService()
    else :
S
Steven Li 已提交
2041
        return MainExec.runClient()
2042

2043
    
S
Steven Li 已提交
2044
    # logger.info("Crash_Gen execution finished")
2045 2046

if __name__ == "__main__":
S
Steven Li 已提交
2047 2048 2049
    exitCode = main()
    # print("Exiting with code: {}".format(exitCode))
    sys.exit(exitCode)