Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo", uses=false)
Using package OpenIPSL with version 3.1.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001417/0.001417, allocations: 98.27 kB / 15.36 MB, free: 0.7344 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.558/1.558, allocations: 223.3 MB / 239.4 MB, free: 5.402 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001541/0.001541, allocations: 188.5 kB / 290.6 MB, free: 2.062 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5846/0.5846, allocations: 91.92 MB / 433.6 MB, free: 10.27 MB / 302.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004414/0.0004416, allocations: 12 kB / 0.5048 GB, free: 7.242 MB / 382.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.415/0.4155, allocations: 72.46 MB / 0.5755 GB, free: 36.88 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII): time 0.00989/0.4254, allocations: 13.74 MB / 0.5889 GB, free: 26.99 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008936/0.4344, allocations: 3.592 MB / 0.5925 GB, free: 24.41 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003826/0.4383, allocations: 37.06 kB / 0.5925 GB, free: 24.4 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002297/0.4406, allocations: 434.3 kB / 0.5929 GB, free: 24.23 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006276/0.4469, allocations: 1.218 MB / 0.5941 GB, free: 23.41 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004161/0.4511, allocations: 1.399 MB / 0.5955 GB, free: 22.66 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006864/0.458, allocations: 3.519 MB / 0.5989 GB, free: 20.93 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002308/0.4603, allocations: 0.791 MB / 0.5997 GB, free: 20.5 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003495/0.4639, allocations: 1.323 MB / 0.601 GB, free: 19.88 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003756/0.4676, allocations: 1.778 MB / 0.6027 GB, free: 19.06 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002237/0.4699, allocations: 140.6 kB / 0.6028 GB, free: 19.06 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003479/0.4734, allocations: 266.5 kB / 0.6031 GB, free: 19.02 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001731/0.4752, allocations: 0.6136 MB / 0.6037 GB, free: 18.65 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.004318/0.4795, allocations: 1.491 MB / 0.6051 GB, free: 17.66 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.006201/0.4857, allocations: 3.713 MB / 0.6088 GB, free: 16.24 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.003e-06/0.4858, allocations: 0.5156 kB / 0.6088 GB, free: 16.24 MB / 430.1 MB
Notification: Performance of FrontEnd: time 2.625e-06/0.4858, allocations: 0 / 0.6088 GB, free: 16.24 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 0.0002041/0.486, allocations: 0 / 0.6088 GB, free: 16.24 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 522
 * Number of variables: 522
Notification: Performance of Generate backend data structure: time 0.02547/0.5115, allocations: 6.125 MB / 0.6147 GB, free: 13.97 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.856e-05/0.5116, allocations: 9.688 kB / 0.6148 GB, free: 13.97 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01697/0.5285, allocations: 2.298 MB / 0.617 GB, free: 13.55 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.008848/0.5375, allocations: 1.871 MB / 0.6188 GB, free: 12.39 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001273/0.5388, allocations: 0.7504 MB / 0.6196 GB, free: 11.74 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002072/0.5409, allocations: 218.2 kB / 0.6198 GB, free: 11.66 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01461/0.5555, allocations: 3.231 MB / 0.6229 GB, free: 10.49 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001073/0.5557, allocations: 1.25 kB / 0.6229 GB, free: 10.49 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0008799/0.5566, allocations: 89.16 kB / 0.623 GB, free: 10.42 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.895e-05/0.5567, allocations: 54.3 kB / 0.6231 GB, free: 10.41 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.009316/0.566, allocations: 2.986 MB / 0.626 GB, free: 9.34 MB / 430.1 MB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.02474/0.5908, allocations: 10.12 MB / 0.6359 GB, free: 15.13 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01358/0.6044, allocations: 4.864 MB / 0.6406 GB, free: 10.13 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006223/0.6107, allocations: 2.169 MB / 0.6427 GB, free: 7.961 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006146/0.6114, allocations: 41.95 kB / 0.6428 GB, free: 7.922 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.007604/0.619, allocations: 2.427 MB / 0.6451 GB, free: 5.469 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=247): time 1.76e-05/0.6191, allocations: 0 / 0.6451 GB, free: 5.469 MB / 446.1 MB
Notification: Performance of matching and sorting (n=247): time 0.1459/0.765, allocations: 36.5 MB / 0.6808 GB, free: 0.8438 MB / 478.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001313/0.7652, allocations: 180.2 kB / 0.681 GB, free: 0.6289 MB / 478.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005967/0.7712, allocations: 2.277 MB / 0.6832 GB, free: 14.33 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.001143/0.7723, allocations: 77.69 kB / 0.6833 GB, free: 14.25 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001407/0.7738, allocations: 1.176 MB / 0.6844 GB, free: 13.06 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.002036/0.7758, allocations: 0.8664 MB / 0.6852 GB, free: 12.18 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0013/0.7771, allocations: 292.4 kB / 0.6855 GB, free: 11.89 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001163/0.7773, allocations: 301.1 kB / 0.6858 GB, free: 11.59 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005749/0.783, allocations: 2.146 MB / 0.6879 GB, free: 9.445 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006068/0.7891, allocations: 2.505 MB / 0.6904 GB, free: 6.848 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.06284/0.852, allocations: 15.43 MB / 0.7054 GB, free: 7.18 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.819e-05/0.8521, allocations: 3.984 kB / 0.7054 GB, free: 7.176 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=413) (initialization): time 0.05143/0.9035, allocations: 15.1 MB / 0.7202 GB, free: 8.004 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001485/0.9037, allocations: 114.8 kB / 0.7203 GB, free: 7.867 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.315e-05/0.9038, allocations: 15.94 kB / 0.7203 GB, free: 7.852 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02168/0.9254, allocations: 6.293 MB / 0.7264 GB, free: 1.559 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003394/0.9289, allocations: 0.5734 MB / 0.727 GB, free: 0.9844 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.07642/1.005, allocations: 24.76 MB / 0.7512 GB, free: 8.07 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006116/1.012, allocations: 0.5908 MB / 0.7518 GB, free: 7.477 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0012/1.013, allocations: 123.9 kB / 0.7519 GB, free: 7.355 MB / 0.545 GB
Warning: Assuming fixed start value for the following 6 variables:
         order3_2.delta:VARIABLE(flow=false start = order3_2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order3_2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3_2.e1q:VARIABLE(start = order3_2.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3_1.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 29
 * Number of states: 0 ()
 * Number of discrete variables: 4 ($whenCondition2,$whenCondition1,$whenCondition4,$whenCondition3)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (325):
 * Single equations (assignments): 320
 * Array equations: 0
 * Algorithm blocks: 4
 * 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
   {(63,26)}
Notification: Performance of prepare postOptimizeDAE: time 0.002886/1.016, allocations: 0.5289 MB / 0.7524 GB, free: 6.812 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001712/1.017, allocations: 300.3 kB / 0.7527 GB, free: 6.516 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04866/1.066, allocations: 14.75 MB / 0.7671 GB, free: 7.711 MB / 0.5606 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.97e-05/1.066, allocations: 24 kB / 0.7671 GB, free: 7.688 MB / 0.5606 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0001018/1.066, allocations: 16 kB / 0.7671 GB, free: 7.672 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.913e-05/1.066, allocations: 15.95 kB / 0.7671 GB, free: 7.656 MB / 0.5606 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.4599/1.526, allocations: 18.33 MB / 0.785 GB, free: 158.7 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.692e-05/1.526, allocations: 1.469 kB / 0.785 GB, free: 158.7 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001946/1.528, allocations: 245.5 kB / 0.7853 GB, free: 158.6 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02908/1.557, allocations: 6.266 MB / 0.7914 GB, free: 158.5 MB / 0.5606 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001209/1.559, allocations: 53.89 kB / 0.7915 GB, free: 158.5 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.08357/1.642, allocations: 25.54 MB / 0.8164 GB, free: 148.7 MB / 0.5606 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.252e-06/1.642, allocations: 5.125 kB / 0.8164 GB, free: 148.7 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.05221/1.695, allocations: 17.3 MB / 0.8333 GB, free: 138.8 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001585/1.696, allocations: 424.4 kB / 0.8337 GB, free: 138.4 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001794/1.698, allocations: 44 kB / 0.8337 GB, free: 138.4 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004752/1.703, allocations: 407.3 kB / 0.8341 GB, free: 138.1 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00308/1.706, allocations: 0.5617 MB / 0.8347 GB, free: 137.8 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0007398/1.707, allocations: 67.22 kB / 0.8347 GB, free: 137.7 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.00331/1.71, allocations: 1.513 MB / 0.8362 GB, free: 136.4 MB / 0.5606 GB
Notification: Performance of sort global known variables: time 1.8e-07/1.71, allocations: 0 / 0.8362 GB, free: 136.4 MB / 0.5606 GB
Notification: Performance of remove unused functions: time 0.005891/1.716, allocations: 1.176 MB / 0.8374 GB, free: 135.3 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 16 (order3_2.delta,order3_2.w,order3_2.e1q,order2.delta,order2.w,order3_1.delta,order3_1.w,order3_1.e1q,Exc1.ExcitationSystem.x_scaled[1],Exc1.derivativeBlock.x,Exc1.firstOrder2.y,Exc1.simpleLagLim.state,Exc2.ExcitationSystem.x_scaled[1],Exc2.derivativeBlock.x,Exc2.firstOrder2.y,Exc2.simpleLagLim.state)
 * Number of discrete variables: 4 ($whenCondition3,$whenCondition4,$whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (156):
 * Single equations (assignments): 151
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(66,22,7.7%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(1,6), (1,6)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0007065/1.717, allocations: 177.3 kB / 0.8375 GB, free: 135.1 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.01928/1.736, allocations: 7.479 MB / 0.8448 GB, free: 128.4 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 7.063e-06/1.736, allocations: 1.75 kB / 0.8448 GB, free: 128.4 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.01482/1.751, allocations: 5.09 MB / 0.8498 GB, free: 123.2 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004797/1.756, allocations: 261.9 kB / 0.8501 GB, free: 122.9 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01105/1.767, allocations: 6.513 MB / 0.8564 GB, free: 116.4 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001097/1.768, allocations: 1.806 MB / 0.8582 GB, free: 114.6 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.001494/1.769, allocations: 268.5 kB / 0.8584 GB, free: 114.3 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005352/1.77, allocations: 159.4 kB / 0.8586 GB, free: 114.1 MB / 0.5606 GB
Notification: Performance of SimCode: time 1.092e-06/1.77, allocations: 0 / 0.8586 GB, free: 114.1 MB / 0.5606 GB
Notification: Performance of Templates: time 0.08767/1.858, allocations: 57.76 MB / 0.915 GB, free: 56.39 MB / 0.5606 GB
make -j1 -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII.makefile
(rm -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII.sim & ./OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRII.pipe 2>&1)