Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII.conf.json
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/Modelica 4.0.0+maint.om/package.mo", uses=false)
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/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 ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 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 Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Running command: translateModel(OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII")
translateModel(OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0009929/0.000993, allocations: 172.9 kB / 15.42 MB, free: 6.207 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.213/1.213, allocations: 222.9 MB / 239 MB, free: 1.797 MB / 186.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008003/0.0008003, allocations: 99.36 kB / 289.8 MB, free: 14.31 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4325/0.4325, allocations: 78.42 MB / 418.9 MB, free: 44.31 MB / 330.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003221/0.0003221, allocations: 8 kB / 498.4 MB, free: 3.898 MB / 362.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3052/0.3055, allocations: 68.75 MB / 0.5539 GB, free: 39.77 MB / 426.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII): time 0.00557/0.3111, allocations: 6.806 MB / 0.5605 GB, free: 37.09 MB / 426.7 MB
Notification: Performance of NFInst.instExpressions: time 0.003133/0.3143, allocations: 1.998 MB / 0.5625 GB, free: 36.3 MB / 426.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004908/0.3148, allocations: 12 kB / 0.5625 GB, free: 36.3 MB / 426.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0005506/0.3154, allocations: 192.1 kB / 0.5627 GB, free: 36.22 MB / 426.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.001491/0.3169, allocations: 0.7128 MB / 0.5634 GB, free: 35.91 MB / 426.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001085/0.318, allocations: 0.5843 MB / 0.5639 GB, free: 35.8 MB / 426.7 MB
Notification: Performance of NFFlatten.flatten: time 0.001381/0.3194, allocations: 1.486 MB / 0.5654 GB, free: 35.46 MB / 426.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0004054/0.3198, allocations: 296.1 kB / 0.5657 GB, free: 35.38 MB / 426.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0007312/0.3205, allocations: 0.5689 MB / 0.5662 GB, free: 35.2 MB / 426.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006707/0.3212, allocations: 0.7428 MB / 0.567 GB, free: 34.91 MB / 426.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.000133/0.3213, allocations: 59.28 kB / 0.567 GB, free: 34.91 MB / 426.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0004101/0.3218, allocations: 185.5 kB / 0.5672 GB, free: 34.88 MB / 426.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001826/0.322, allocations: 265.5 kB / 0.5674 GB, free: 34.72 MB / 426.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003808/0.3223, allocations: 0.5949 MB / 0.568 GB, free: 34.34 MB / 426.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.0009785/0.3233, allocations: 1.564 MB / 0.5696 GB, free: 33.75 MB / 426.7 MB
Notification: Performance of FrontEnd - DAE generated: time 2.795e-06/0.3233, allocations: 4 kB / 0.5696 GB, free: 33.75 MB / 426.7 MB
Notification: Performance of FrontEnd: time 1.203e-06/0.3233, allocations: 0 / 0.5696 GB, free: 33.75 MB / 426.7 MB
Notification: Performance of Transformations before backend: time 1.247e-05/0.3234, allocations: 0 / 0.5696 GB, free: 33.75 MB / 426.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 189
 * Number of variables: 189
Notification: Performance of Generate backend data structure: time 0.006588/0.33, allocations: 2.427 MB / 0.5719 GB, free: 32.63 MB / 426.7 MB
Notification: Performance of prepare preOptimizeDAE: time 2.983e-05/0.33, allocations: 12.53 kB / 0.5719 GB, free: 32.63 MB / 426.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004318/0.3343, allocations: 0.7696 MB / 0.5727 GB, free: 32.48 MB / 426.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001037/0.3354, allocations: 0.8803 MB / 0.5736 GB, free: 31.89 MB / 426.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001907/0.3356, allocations: 257.9 kB / 0.5738 GB, free: 31.66 MB / 426.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001338/0.3357, allocations: 90.22 kB / 0.5739 GB, free: 31.63 MB / 426.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001688/0.3374, allocations: 1.091 MB / 0.5749 GB, free: 31.21 MB / 426.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.471e-05/0.3374, allocations: 0 / 0.5749 GB, free: 31.21 MB / 426.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.935e-05/0.3375, allocations: 29.38 kB / 0.575 GB, free: 31.19 MB / 426.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.248e-05/0.3375, allocations: 19.47 kB / 0.575 GB, free: 31.19 MB / 426.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001606/0.3391, allocations: 0.9812 MB / 0.576 GB, free: 30.86 MB / 426.7 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.004846/0.344, allocations: 3.816 MB / 0.5797 GB, free: 28.66 MB / 426.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.006195/0.3502, allocations: 2.374 MB / 0.582 GB, free: 27.83 MB / 426.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001111/0.3513, allocations: 0.6714 MB / 0.5827 GB, free: 27.56 MB / 426.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 9.553e-05/0.3514, allocations: 17.23 kB / 0.5827 GB, free: 27.56 MB / 426.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001231/0.3527, allocations: 0.8137 MB / 0.5835 GB, free: 27.24 MB / 426.7 MB
Notification: Performance of pre-optimization done (n=95): time 2.003e-06/0.3527, allocations: 4 kB / 0.5835 GB, free: 27.24 MB / 426.7 MB
Notification: Performance of matching and sorting (n=95): time 0.0251/0.3778, allocations: 7.194 MB / 0.5905 GB, free: 25.84 MB / 426.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.838e-05/0.3779, allocations: 118.3 kB / 0.5906 GB, free: 25.71 MB / 426.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001384/0.3792, allocations: 1.086 MB / 0.5917 GB, free: 25.27 MB / 426.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001196/0.3794, allocations: 46.94 kB / 0.5917 GB, free: 25.22 MB / 426.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0002741/0.3797, allocations: 499.1 kB / 0.5922 GB, free: 24.83 MB / 426.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0002525/0.3799, allocations: 350.7 kB / 0.5925 GB, free: 24.56 MB / 426.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001766/0.3801, allocations: 146.4 kB / 0.5927 GB, free: 24.49 MB / 426.7 MB
Notification: Performance of setup shared object (initialization): time 3.256e-05/0.3801, allocations: 305.8 kB / 0.593 GB, free: 24.19 MB / 426.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001462/0.3816, allocations: 0.7549 MB / 0.5937 GB, free: 23.91 MB / 426.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00153/0.3831, allocations: 0.9195 MB / 0.5946 GB, free: 23.48 MB / 426.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02171/0.4049, allocations: 5.558 MB / 0.6 GB, free: 21.75 MB / 426.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.073e-05/0.4049, allocations: 1.188 kB / 0.6 GB, free: 21.75 MB / 426.7 MB
Notification: Performance of matching and sorting (n=192) (initialization): time 0.01217/0.4171, allocations: 4.359 MB / 0.6043 GB, free: 19.89 MB / 426.7 MB
Notification: Performance of prepare postOptimizeDAE: time 4.025e-05/0.4172, allocations: 66.67 kB / 0.6043 GB, free: 19.82 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.255e-05/0.4172, allocations: 3.281 kB / 0.6043 GB, free: 19.82 MB / 426.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005047/0.4222, allocations: 1.592 MB / 0.6059 GB, free: 19.11 MB / 426.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001172/0.4234, allocations: 275.1 kB / 0.6062 GB, free: 19.05 MB / 426.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01559/0.439, allocations: 7.846 MB / 0.6138 GB, free: 13.73 MB / 426.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001608/0.4407, allocations: 202.7 kB / 0.614 GB, free: 13.53 MB / 426.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001009/0.4408, allocations: 51.98 kB / 0.6141 GB, free: 13.48 MB / 426.7 MB
Warning: Assuming fixed start value for the following 4 variables:
         order3.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order3.delta:VARIABLE(flow=false start = order3.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 13
 * Number of states: 0 ()
 * Number of discrete variables: 2 ($whenCondition2,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (160):
 * Single equations (assignments): 157
 * Array equations: 0
 * Algorithm blocks: 2
 * 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): 1 system
   {(19,14,24.4%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0004545/0.4412, allocations: 305.3 kB / 0.6144 GB, free: 13.16 MB / 426.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001536/0.4414, allocations: 122.5 kB / 0.6145 GB, free: 13.04 MB / 426.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0109/0.4523, allocations: 3.869 MB / 0.6182 GB, free: 9.145 MB / 426.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.195e-06/0.4523, allocations: 12 kB / 0.6183 GB, free: 9.133 MB / 426.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.412e-06/0.4523, allocations: 4 kB / 0.6183 GB, free: 9.129 MB / 426.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.899e-06/0.4523, allocations: 11.92 kB / 0.6183 GB, free: 9.117 MB / 426.7 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 postOpt removeSimpleEquations (simulation): time 0.01181/0.4642, allocations: 5.451 MB / 0.6236 GB, free: 3.559 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.733e-06/0.4642, allocations: 0 / 0.6236 GB, free: 3.559 MB / 426.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0005118/0.4647, allocations: 99.89 kB / 0.6237 GB, free: 3.461 MB / 426.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.004639/0.4694, allocations: 1.606 MB / 0.6253 GB, free: 1.848 MB / 426.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001087/0.4695, allocations: 19.98 kB / 0.6253 GB, free: 1.828 MB / 426.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01698/0.4865, allocations: 8.787 MB / 0.6339 GB, free: 8.758 MB / 442.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.154e-06/0.4865, allocations: 0 / 0.6339 GB, free: 8.758 MB / 442.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01301/0.4995, allocations: 4.729 MB / 0.6385 GB, free: 3.996 MB / 442.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004229/0.5, allocations: 223.4 kB / 0.6387 GB, free: 3.758 MB / 442.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001558/0.5002, allocations: 16 kB / 0.6387 GB, free: 3.742 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001054/0.5012, allocations: 107.9 kB / 0.6388 GB, free: 3.637 MB / 442.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005401/0.5018, allocations: 196.7 kB / 0.639 GB, free: 3.441 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.787e-05/0.5018, allocations: 23.98 kB / 0.639 GB, free: 3.418 MB / 442.7 MB
Notification: Performance of sorting global known variables: time 0.0009603/0.5028, allocations: 0.7354 MB / 0.6397 GB, free: 2.676 MB / 442.7 MB
Notification: Performance of sort global known variables: time 1.91e-07/0.5028, allocations: 4.188 kB / 0.6397 GB, free: 2.672 MB / 442.7 MB
Notification: Performance of remove unused functions: time 0.001216/0.504, allocations: 475.4 kB / 0.6402 GB, free: 2.207 MB / 442.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 9 (order2.delta,order2.w,order3.delta,order3.w,order3.e1q,aVRTypeII.ExcitationSystem.x_scaled[1],aVRTypeII.derivativeBlock.x,aVRTypeII.firstOrder2.y,aVRTypeII.simpleLagLim.state)
 * Number of discrete variables: 2 ($whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (63):
 * Single equations (assignments): 60
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(20,12,23.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,6)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0002346/0.5043, allocations: 136.6 kB / 0.6403 GB, free: 2.062 MB / 442.7 MB
Notification: Performance of simCode: created initialization part: time 0.005792/0.5101, allocations: 2.519 MB / 0.6428 GB, free: 15.51 MB / 458.7 MB
Notification: Performance of simCode: created event and clocks part: time 3.727e-06/0.5101, allocations: 0 / 0.6428 GB, free: 15.51 MB / 458.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.004117/0.5142, allocations: 1.588 MB / 0.6443 GB, free: 13.88 MB / 458.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001059/0.5153, allocations: 119.8 kB / 0.6445 GB, free: 13.77 MB / 458.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003983/0.5193, allocations: 2.619 MB / 0.647 GB, free: 11.13 MB / 458.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.000638/0.52, allocations: 1.12 MB / 0.6481 GB, free: 9.98 MB / 458.7 MB
Notification: Performance of simCode: alias equations: time 0.0005149/0.5205, allocations: 120.1 kB / 0.6482 GB, free: 9.859 MB / 458.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002906/0.5208, allocations: 75.41 kB / 0.6483 GB, free: 9.785 MB / 458.7 MB
Notification: Performance of SimCode: time 5.01e-07/0.5208, allocations: 0 / 0.6483 GB, free: 9.785 MB / 458.7 MB
Notification: Performance of Templates: time 0.03078/0.5516, allocations: 21.1 MB / 0.6689 GB, free: 4.719 MB / 474.7 MB
make -j1 -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII.makefile
(rm -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII.pipe >> ../files/OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII.sim & ./OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.ThirdOrder_AVRII.pipe 2>&1)