Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_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: "" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRII,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_TwoArea_SixthOrder_AVRII",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRII,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_TwoArea_SixthOrder_AVRII",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008925/0.0008925, allocations: 90.75 kB / 15.06 MB, free: 1.098 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.401/1.401, allocations: 223.3 MB / 239 MB, free: 5.609 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.001224/0.001224, allocations: 180.5 kB / 290.3 MB, free: 2.312 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4871/0.4871, allocations: 79.47 MB / 420.8 MB, free: 3.934 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003922/0.0003922, allocations: 12 kB / 0.4892 GB, free: 5.496 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4217/0.4222, allocations: 69.51 MB / 0.5571 GB, free: 37.78 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRII): time 0.01606/0.4383, allocations: 21.02 MB / 0.5776 GB, free: 28.44 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01169/0.45, allocations: 4.996 MB / 0.5825 GB, free: 24.79 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004662/0.4547, allocations: 52.72 kB / 0.5826 GB, free: 24.79 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002186/0.4569, allocations: 0.61 MB / 0.5831 GB, free: 24.52 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006833/0.4638, allocations: 1.773 MB / 0.5849 GB, free: 23.29 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004738/0.4685, allocations: 1.768 MB / 0.5866 GB, free: 22.42 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00707/0.4756, allocations: 5.243 MB / 0.5917 GB, free: 19.68 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002381/0.478, allocations: 1.068 MB / 0.5928 GB, free: 19.2 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003394/0.4814, allocations: 2.025 MB / 0.5947 GB, free: 18.27 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003737/0.4851, allocations: 2.628 MB / 0.5973 GB, free: 17.07 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0008136/0.486, allocations: 238.9 kB / 0.5975 GB, free: 17.07 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001488/0.4875, allocations: 372 kB / 0.5979 GB, free: 17.03 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0007669/0.4882, allocations: 0.9159 MB / 0.5988 GB, free: 16.47 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001318/0.4896, allocations: 0.8403 MB / 0.5996 GB, free: 15.97 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.004318/0.4939, allocations: 4.679 MB / 0.6042 GB, free: 14.49 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.543e-06/0.4939, allocations: 0.8438 kB / 0.6042 GB, free: 14.49 MB / 430.1 MB
Notification: Performance of FrontEnd: time 2.544e-06/0.4939, allocations: 9.156 kB / 0.6042 GB, free: 14.48 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 0.0001645/0.4941, allocations: 1.031 kB / 0.6042 GB, free: 14.48 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: 895
 * Number of variables: 895
Notification: Performance of Generate backend data structure: time 0.02818/0.5223, allocations: 8.548 MB / 0.6125 GB, free: 11.34 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.951e-05/0.5223, allocations: 9.844 kB / 0.6125 GB, free: 11.34 MB / 430.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.00167/0.524, allocations: 494 kB / 0.613 GB, free: 11.04 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02646/0.5505, allocations: 4.054 MB / 0.617 GB, free: 10.32 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006803/0.5573, allocations: 2.537 MB / 0.6195 GB, free: 8.832 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0008869/0.5582, allocations: 0.9916 MB / 0.6204 GB, free: 8 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007773/0.559, allocations: 356.9 kB / 0.6208 GB, free: 7.879 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01006/0.5691, allocations: 4.984 MB / 0.6256 GB, free: 5.691 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 5.697e-05/0.5692, allocations: 0 / 0.6256 GB, free: 5.691 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000375/0.5695, allocations: 144.8 kB / 0.6258 GB, free: 5.578 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.146e-05/0.5696, allocations: 83.88 kB / 0.6258 GB, free: 5.578 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008218/0.5778, allocations: 4.498 MB / 0.6302 GB, free: 1.977 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.03399/0.6119, allocations: 18.91 MB / 0.6487 GB, free: 13.88 MB / 462.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01237/0.6243, allocations: 5.752 MB / 0.6543 GB, free: 8.051 MB / 462.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006614/0.6309, allocations: 3.053 MB / 0.6573 GB, free: 4.988 MB / 462.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006346/0.6316, allocations: 66.84 kB / 0.6574 GB, free: 4.926 MB / 462.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.007989/0.6396, allocations: 3.443 MB / 0.6607 GB, free: 1.441 MB / 462.1 MB
Notification: Performance of pre-optimization done (n=385): time 1.056e-05/0.6396, allocations: 1.688 kB / 0.6607 GB, free: 1.441 MB / 462.1 MB
Notification: Performance of matching and sorting (n=385): time 0.2856/0.9252, allocations: 79.83 MB / 0.7387 GB, free: 1.418 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001326/0.9254, allocations: 145.8 kB / 0.7388 GB, free: 1.25 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005673/0.9311, allocations: 3.243 MB / 0.742 GB, free: 13.98 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.001284/0.9324, allocations: 89.7 kB / 0.7421 GB, free: 13.89 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001703/0.9341, allocations: 1.971 MB / 0.744 GB, free: 11.91 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002573/0.9367, allocations: 1.328 MB / 0.7453 GB, free: 10.56 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003985/0.9371, allocations: 84 kB / 0.7454 GB, free: 10.48 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0003053/0.9374, allocations: 472.9 kB / 0.7458 GB, free: 10.01 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00598/0.9434, allocations: 2.862 MB / 0.7486 GB, free: 7.141 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006887/0.9503, allocations: 3.491 MB / 0.752 GB, free: 3.43 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.006677/0.957, allocations: 3.136 MB / 0.7551 GB, free: 284 kB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.656e-05/0.9571, allocations: 12 kB / 0.7551 GB, free: 272 kB / 0.545 GB
Notification: Performance of matching and sorting (n=546) (initialization): time 0.3729/1.33, allocations: 20.88 MB / 0.7755 GB, free: 156.1 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 6.689e-05/1.33, allocations: 63.16 kB / 0.7756 GB, free: 156 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.228e-05/1.33, allocations: 15.84 kB / 0.7756 GB, free: 156 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02557/1.356, allocations: 8.708 MB / 0.7841 GB, free: 156 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003471/1.359, allocations: 0.672 MB / 0.7847 GB, free: 156 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08641/1.446, allocations: 36.79 MB / 0.8207 GB, free: 148.2 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006471/1.452, allocations: 0.7567 MB / 0.8214 GB, free: 148.1 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007579/1.453, allocations: 154.2 kB / 0.8215 GB, free: 148 MB / 0.5606 GB
Warning: Assuming fixed start value for the following 4 variables:
         order6.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         aVRTypeII.firstOrder2.y:VARIABLE(flow=false start = aVRTypeII.firstOrder2.y_start fixed = true )  "Connector of Real output signal" type: Real
         order6.e2d:VARIABLE(start = order6.e2d0 unit = "1" fixed = true )  "d-axis sub-transient voltage" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 65
 * Number of states: 0 ()
 * 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 initialization (408):
 * Single equations (assignments): 404
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(1,100.0%) 1}
 * Non-linear torn systems: 1 {93 45}
Notification: Performance of prepare postOptimizeDAE: time 0.001829/1.455, allocations: 0.6752 MB / 0.8222 GB, free: 147.5 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001091/1.456, allocations: 455.3 kB / 0.8226 GB, free: 147.3 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04993/1.506, allocations: 20.66 MB / 0.8428 GB, free: 136.2 MB / 0.5606 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.782e-05/1.506, allocations: 37.88 kB / 0.8429 GB, free: 136.1 MB / 0.5606 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.771e-05/1.506, allocations: 32 kB / 0.8429 GB, free: 136.1 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001057/1.506, allocations: 23.27 kB / 0.8429 GB, free: 136.1 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.05997/1.566, allocations: 25.67 MB / 0.868 GB, free: 110.6 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.918e-05/1.566, allocations: 0 / 0.868 GB, free: 110.6 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002347/1.568, allocations: 391.5 kB / 0.8684 GB, free: 110.2 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02369/1.592, allocations: 8.679 MB / 0.8768 GB, free: 101.5 MB / 0.5606 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001082/1.593, allocations: 67.98 kB / 0.8769 GB, free: 101.5 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.06695/1.66, allocations: 32.65 MB / 0.9088 GB, free: 68.54 MB / 0.5606 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.07e-06/1.66, allocations: 4 kB / 0.9088 GB, free: 68.54 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.059/1.719, allocations: 24.29 MB / 0.9325 GB, free: 44.16 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0017/1.721, allocations: 0.5609 MB / 0.9331 GB, free: 43.58 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001377/1.722, allocations: 68 kB / 0.9331 GB, free: 43.51 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004569/1.727, allocations: 0.5074 MB / 0.9336 GB, free: 43 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003646/1.731, allocations: 0.7845 MB / 0.9344 GB, free: 42.22 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0004929/1.731, allocations: 99.98 kB / 0.9345 GB, free: 42.12 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.002957/1.734, allocations: 2.11 MB / 0.9365 GB, free: 40.01 MB / 0.5606 GB
Notification: Performance of sort global known variables: time 1.6e-07/1.734, allocations: 0 / 0.9365 GB, free: 40.01 MB / 0.5606 GB
Notification: Performance of remove unused functions: time 0.007405/1.742, allocations: 1.828 MB / 0.9383 GB, free: 38.18 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 13 (order3.delta,order3.w,order3.e1q,order6.delta,order6.w,order6.e1q,order6.e1d,order6.e2q,order6.e2d,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 (249):
 * Single equations (assignments): 246
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(98,5.5%) 38}
 * Non-linear torn systems: 1 {1 6}
Notification: Performance of Backend phase and start with SimCode phase: time 0.05862/1.8, allocations: 24.97 MB / 0.9627 GB, free: 13.14 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.02472/1.825, allocations: 10.45 MB / 0.9729 GB, free: 2.641 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 7.444e-06/1.825, allocations: 4 kB / 0.9729 GB, free: 2.637 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.01937/1.844, allocations: 7.077 MB / 0.9798 GB, free: 11.53 MB / 0.5762 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006327/1.851, allocations: 384.8 kB / 0.9802 GB, free: 11.15 MB / 0.5762 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3771/2.228, allocations: 51.81 MB / 1.031 GB, free: 163.6 MB / 0.5762 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002734/2.231, allocations: 2.463 MB / 1.033 GB, free: 162.8 MB / 0.5762 GB
Notification: Performance of simCode: alias equations: time 0.002213/2.233, allocations: 371.3 kB / 1.034 GB, free: 162.7 MB / 0.5762 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002704/2.236, allocations: 1.98 MB / 1.035 GB, free: 162.1 MB / 0.5762 GB
Notification: Performance of SimCode: time 1.032e-06/2.236, allocations: 0.5625 kB / 1.035 GB, free: 162.1 MB / 0.5762 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1725/2.408, allocations: 118 MB / 1.151 GB, free: 57.46 MB / 0.5762 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0001581/2.408, allocations: 218.6 kB / 1.151 GB, free: 57.22 MB / 0.5762 GB
Notification: Performance of buildModelFMU: Generate platform static: time 5.443/7.851, allocations: 0 / 1.151 GB, free: 57.22 MB / 0.5762 GB
(rm -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRII.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRII.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRII.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRII.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator  --tempDir=temp_OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_TwoArea_SixthOrder_AVRII_fmu --startTime=0 --stopTime=20 --timeout=50 --tolerance=1e-06 OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_TwoArea_SixthOrder_AVRII.fmu > OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.SixthOrder_AVRII.pipe 2>&1)
Environment - environmentSimulation: