Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Dynawo_dev_Dynawo.Examples.SVarC.SVarCStepURef.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "[:1:22-1:33:writable] Warning: Modelica only supports 32-bit signed integers! Transforming: 13743895347 into a real Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.0007267/0.0007267, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFInstanceAPI.mkTop(): time 0.001294/0.00202, allocations: 0 / 0, free: 0 / 0 " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.0006557/0.0006557, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFInstanceAPI.mkTop(): time 0.001408/0.002063, allocations: 0 / 0, free: 0 / 0 " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 0.199/0.199, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFInstanceAPI.mkTop(): time 0.1151/0.314, allocations: 0 / 0, free: 0 / 0 " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/package.mo): time 0.1673/0.1673, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFInstanceAPI.mkTop(): time 0.07551/0.2428, allocations: 0 / 0, free: 0 / 0 " [Timeout remaining time 180] Using package Dynawo with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/package.mo) Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo) Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo) Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo) Running command: simulate(Dynawo.Examples.SVarC.SVarCStepURef,startTime=0,stopTime=3,tolerance=1e-06,numberOfIntervals=500,outputFormat="mat",variableFilter="time|sVarCStandard.injector.QInjPu",fileNamePrefix="Dynawo_dev_Dynawo.Examples.SVarC.SVarCStepURef",simflags="-lv=LOG_STATS -s=dassl -abortSlowSimulation -alarm=240 -emit_protected -lv LOG_STATS") simulate(Dynawo.Examples.SVarC.SVarCStepURef,startTime=0,stopTime=3,tolerance=1e-06,numberOfIntervals=500,outputFormat="mat",variableFilter="time|sVarCStandard.injector.QInjPu",fileNamePrefix="Dynawo_dev_Dynawo.Examples.SVarC.SVarCStepURef",simflags="-lv=LOG_STATS -s=dassl -abortSlowSimulation -alarm=240 -emit_protected -lv LOG_STATS") [Timeout 900] "Notification: Performance of FrontEnd - loaded program: time 9.22e-07/9.22e-07, allocations: 0 / 0, free: 0 / 0 Notification: Performance of FrontEnd - Absyn->SCode: time 4.407e-05/4.499e-05, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFInst.instantiate(Dynawo.Examples.SVarC.SVarCStepURef): time 0.007325/0.00737, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFInst.instExpressions: time 0.007522/0.01489, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFInst.updateImplicitVariability: time 0.00187/0.01676, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFTyping.typeComponents: time 0.001582/0.01834, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFTyping.typeBindings: time 0.00238/0.02072, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFTyping.typeClassSections: time 0.003867/0.02459, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFFlatten.flatten: time 0.002905/0.0275, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFFlatten.resolveConnections: time 0.0008733/0.02837, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFEvalConstants.evaluate: time 0.001658/0.03003, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFSimplifyModel.simplify: time 0.0006257/0.03065, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFPackage.collectConstants: time 0.0002231/0.03088, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFFlatten.collectFunctions: time 0.0006719/0.03155, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFScalarize.scalarize: time 0.0003683/0.03192, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFVerifyModel.verify: time 0.0003554/0.03227, allocations: 0 / 0, free: 0 / 0 Notification: Performance of NFConvertDAE.convert: time 0.002357/0.03463, allocations: 0 / 0, free: 0 / 0 Notification: Performance of FrontEnd - DAE generated: time 0.0002467/0.03487, allocations: 0 / 0, free: 0 / 0 Notification: Performance of FrontEnd: time 6.853e-06/0.03488, allocations: 0 / 0, free: 0 / 0 Notification: Performance of Transformations before backend: time 3.083e-05/0.03491, allocations: 0 / 0, free: 0 / 0 Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 149 * Number of variables: 149 Notification: Performance of Generate backend data structure: time 0.003096/0.03801, allocations: 0 / 0, free: 0 / 0 Notification: Performance of prepare preOptimizeDAE: time 0.0002081/0.03822, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0008661/0.03908, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt evaluateParameters (simulation): time 0.0007589/0.03984, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002245/0.04007, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001044/0.04017, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt clockPartitioning (simulation): time 0.001068/0.04124, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt findStateOrder (simulation): time 1.438e-05/0.04125, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.679e-05/0.04133, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.414e-05/0.04134, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0004446/0.04179, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.003105/0.04489, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt comSubExp (simulation): time 0.0006326/0.04553, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt resolveLoops (simulation): time 0.0001618/0.04569, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt evalFunc (simulation): time 4.74e-05/0.04574, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0006798/0.04642, allocations: 0 / 0, free: 0 / 0 Notification: Performance of pre-optimization done (n=65): time 1.085e-05/0.04643, allocations: 0 / 0, free: 0 / 0 Notification: Performance of matching and sorting (n=65): time 0.001556/0.04798, allocations: 0 / 0, free: 0 / 0 Notification: Performance of inlineWhenForInitialization (initialization): time 7.414e-05/0.04806, allocations: 0 / 0, free: 0 / 0 Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0007208/0.04878, allocations: 0 / 0, free: 0 / 0 Notification: Performance of collectPreVariables (initialization): time 0.0001471/0.04892, allocations: 0 / 0, free: 0 / 0 Notification: Performance of collectInitialEqns (initialization): time 0.0002542/0.04918, allocations: 0 / 0, free: 0 / 0 Notification: Performance of collectInitialBindings (initialization): time 0.0001673/0.04935, allocations: 0 / 0, free: 0 / 0 Notification: Performance of simplifyInitialFunctions (initialization): time 0.000276/0.04962, allocations: 0 / 0, free: 0 / 0 Notification: Performance of setup shared object (initialization): time 0.0001775/0.0498, allocations: 0 / 0, free: 0 / 0 Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001676/0.04997, allocations: 0 / 0, free: 0 / 0 Notification: Performance of partitionIndependentBlocks (initialization): time 0.0002583/0.05023, allocations: 0 / 0, free: 0 / 0 Notification: Performance of analyzeInitialSystem (initialization): time 0.0005637/0.05079, allocations: 0 / 0, free: 0 / 0 Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.287e-06/0.0508, allocations: 0 / 0, free: 0 / 0 Notification: Performance of matching and sorting (n=92) (initialization): time 0.001434/0.05223, allocations: 0 / 0, free: 0 / 0 Notification: Performance of prepare postOptimizeDAE: time 3.688e-05/0.05227, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.717e-05/0.05229, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt tearingSystem (initialization): time 0.0008166/0.0531, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0003414/0.05344, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003355/0.0568, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005483/0.05735, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.782e-05/0.0574, allocations: 0 / 0, free: 0 / 0 Warning: Assuming fixed start value for the following 9 variables: sVarCStandard.injector.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = sVarCStandard.injector.State0 fixed = true ) \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined) line.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = line.State0 fixed = true ) \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined) line.running:DISCRETE(flow=false start = line.Running0 fixed = true ) \"Indicates if the component is running or not\" type: Boolean sVarCStandard.injector.running:DISCRETE(flow=false start = sVarCStandard.injector.Running0 fixed = true ) \"Indicates if the component is running or not\" type: Boolean sVarCStandard.modeHandling.timerModeChangeUp:DISCRETE(start = 1e60 unit = \"s\" fixed = true ) \"Timer for the transition from standby to running mode for high voltage values\" type: Real sVarCStandard.modeHandling.timerModeChangeDown:DISCRETE(start = 1e60 unit = \"s\" fixed = true ) \"Timer for the transition from standby to running mode for low voltage values\" type: Real sVarCStandard.modeHandling.URefAuto:DISCRETE(start = sVarCStandard.modeHandling.URef0 unit = \"kV\" fixed = true ) \"Voltage reference for the regulation if the static var compensator switches from standy to running mode automatically in kV\" type: Real sVarCStandard.blockingFunction.blocked:DISCRETE(flow=false fixed = true ) \"Whether the static var compensator is blocked due to very low voltages\" type: Boolean sVarCStandard.modeHandling.modeAuto:DISCRETE(flow=false min = Dynawo.Electrical.StaticVarCompensators.BaseControls.Mode.OFF max = Dynawo.Electrical.StaticVarCompensators.BaseControls.Mode.RUNNING_V start = sVarCStandard.modeHandling.Mode0 fixed = true ) \"Mode of the static var compensator when in automatic configuration\" type: enumeration(OFF, STANDBY, RUNNING_V) Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 15 * Number of states: 0 () * Number of discrete variables: 43 ($PRE.sVarCStandard.injector.state,sVarCStandard.injector.state,$PRE.line.state,line.state,sVarCStandard.regulation.limitations.limIntegratorMax.local_reset,sVarCStandard.regulation.limitations.limIntegratorMin.local_reset,sVarCStandard.regulation.integrator.local_reset,$PRE.line.running,$PRE.sVarCStandard.injector.running,$PRE.sVarCStandard.modeHandling.timerModeChangeUp,$PRE.sVarCStandard.modeHandling.timerModeChangeDown,$PRE.sVarCStandard.modeHandling.URefAuto,$PRE.sVarCStandard.blockingFunction.blocked,$PRE.sVarCStandard.modeHandling.modeAuto,sVarCStandard.modeHandling.modeManual,sVarCStandard.blockingFunction.blocked,sVarCStandard.modeHandling.URefAuto,sVarCStandard.modeHandling.timerModeChangeDown,sVarCStandard.modeHandling.timerModeChangeUp,sVarCStandard.modeHandling.modeAuto,sVarCStandard.modeHandling.mode,sVarCStandard.injector.running,line.running,$whenCondition2,$whenCondition1,$whenCondition3,$whenCondition5,$whenCondition4,$whenCondition6,$whenCondition8,$whenCondition7,$whenCondition10,$whenCondition9,$whenCondition11,$whenCondition13,$whenCondition12,$whenCondition15,$whenCondition14,line.switchOffSignal1,line.switchOffSignal2,sVarCStandard.switchOffSignal1,sVarCStandard.switchOffSignal2,sVarCStandard.switchOffSignal3) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (76): * Single equations (assignments): 75 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 0 systems * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(5,12)} Notification: Performance of prepare postOptimizeDAE: time 0.0008045/0.05821, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001763/0.05839, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001492/0.05988, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.25e-05/0.05989, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.025e-06/0.0599, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt removeConstants (simulation): time 0.0002338/0.06013, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002176/0.06035, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0002376/0.06059, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002524/0.06084, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.001123/0.06196, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0009922/0.06295, allocations: 0 / 0, free: 0 / 0 Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00018/0.06313, allocations: 0 / 0, free: 0 / 0 Notification: Performance of sorting global known variables: time 0.0003823/0.06352, allocations: 0 / 0, free: 0 / 0 Notification: Performance of Backend: time 0.0002244/0.06374, allocations: 0 / 0, free: 0 / 0 Notification: Performance of simCode: created initialization part: time 0.002772/0.06651, allocations: 0 / 0, free: 0 / 0 Notification: Performance of SimCode: time 0.00323/0.06974, allocations: 0 / 0, free: 0 / 0 Notification: Performance of Templates: time 0.01381/0.08355, allocations: 0 / 0, free: 0 / 0 " [Timeout remaining time 900] diffSimulationResults("Dynawo_dev_Dynawo.Examples.SVarC.SVarCStepURef_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/Dynawo/dynawo/sources/Models/Modelica/Dynawo/Examples/references/Dynawo.Examples.SVarC.SVarCStepURef_res.csv","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660] "" [Timeout remaining time 660] Reference file matches [Calling sys.exit(0), Time elapsed: 1.3485468910075724]