Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.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.TwoAreas.Two_Areas_PSSE,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Examples_TwoAreas_Two_Areas_PSSE",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Examples_TwoAreas_Two_Areas_PSSE",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.001669/0.001669, allocations: 108 kB / 15.61 MB, free: 148 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.437/1.437, allocations: 222.9 MB / 239.3 MB, free: 4.594 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.001552/0.001552, allocations: 192.5 kB / 289.6 MB, free: 2.316 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5271/0.5271, allocations: 91.9 MB / 431.6 MB, free: 9.305 MB / 302.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.00252/0.00252, allocations: 83.86 kB / 0.502 GB, free: 7.672 MB / 382.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4031/0.4056, allocations: 71.26 MB / 0.5716 GB, free: 36.75 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE): time 0.01111/0.4168, allocations: 14.99 MB / 0.5862 GB, free: 26.71 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.009607/0.4264, allocations: 4.592 MB / 0.5907 GB, free: 23.41 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003826/0.4303, allocations: 29.69 kB / 0.5907 GB, free: 23.41 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002751/0.4331, allocations: 0.7853 MB / 0.5915 GB, free: 23 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.007201/0.4403, allocations: 1.881 MB / 0.5933 GB, free: 21.72 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003676/0.444, allocations: 1.422 MB / 0.5947 GB, free: 20.99 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.007346/0.4514, allocations: 4.494 MB / 0.5991 GB, free: 18.7 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001561/0.453, allocations: 0.8003 MB / 0.5999 GB, free: 18.3 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003136/0.4561, allocations: 1.8 MB / 0.6016 GB, free: 17.37 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003862/0.46, allocations: 2.373 MB / 0.604 GB, free: 16.18 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001418/0.4615, allocations: 406.6 kB / 0.6043 GB, free: 16.18 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002355/0.4638, allocations: 0.6279 MB / 0.605 GB, free: 16.12 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0008284/0.4647, allocations: 0.9433 MB / 0.6059 GB, free: 15.47 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003049/0.4677, allocations: 1.982 MB / 0.6078 GB, free: 14.1 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.008879/0.4766, allocations: 5.213 MB / 0.6129 GB, free: 11.87 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.677e-06/0.4767, allocations: 1.375 kB / 0.6129 GB, free: 11.87 MB / 430.1 MB
Notification: Performance of FrontEnd: time 2.355e-06/0.4767, allocations: 0 / 0.6129 GB, free: 11.87 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 0.0002148/0.4769, allocations: 0 / 0.6129 GB, free: 11.87 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: 493
 * Number of variables: 493
Notification: Performance of Generate backend data structure: time 0.03587/0.5128, allocations: 8.159 MB / 0.6209 GB, free: 8.93 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.384e-05/0.5128, allocations: 9.031 kB / 0.6209 GB, free: 8.93 MB / 430.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0006097/0.5135, allocations: 287.7 kB / 0.6212 GB, free: 8.75 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01212/0.5256, allocations: 1.749 MB / 0.6229 GB, free: 8.164 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005523/0.5312, allocations: 2.177 MB / 0.625 GB, free: 6.473 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007319/0.5319, allocations: 0.6639 MB / 0.6256 GB, free: 5.883 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0009715/0.5329, allocations: 242.1 kB / 0.6259 GB, free: 5.797 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005908/0.5388, allocations: 2.687 MB / 0.6285 GB, free: 4.113 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.527e-05/0.5389, allocations: 4 kB / 0.6285 GB, free: 4.109 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002376/0.5391, allocations: 76 kB / 0.6286 GB, free: 4.035 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.868e-05/0.5392, allocations: 48 kB / 0.6286 GB, free: 3.988 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005491/0.5446, allocations: 2.83 MB / 0.6314 GB, free: 1.156 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.02224/0.5669, allocations: 11.06 MB / 0.6422 GB, free: 5.559 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01237/0.5793, allocations: 4.983 MB / 0.647 GB, free: 368 kB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004616/0.584, allocations: 1.923 MB / 0.6489 GB, free: 14.43 MB / 462.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004402/0.5844, allocations: 49.95 kB / 0.649 GB, free: 14.38 MB / 462.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.202e-05/0.5845, allocations: 56.56 kB / 0.649 GB, free: 14.32 MB / 462.1 MB
Notification: Performance of pre-optimization done (n=249): time 5.63e-06/0.5845, allocations: 0 / 0.649 GB, free: 14.32 MB / 462.1 MB
Notification: Performance of matching and sorting (n=249): time 0.1312/0.7157, allocations: 29.55 MB / 0.6779 GB, free: 0.6953 MB / 478.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.424e-05/0.7158, allocations: 77.41 kB / 0.678 GB, free: 0.6094 MB / 478.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006214/0.722, allocations: 2.902 MB / 0.6808 GB, free: 13.71 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.001187/0.7233, allocations: 81.7 kB / 0.6809 GB, free: 13.62 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001176/0.7244, allocations: 1.256 MB / 0.6821 GB, free: 12.35 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001751/0.7262, allocations: 0.9052 MB / 0.683 GB, free: 11.43 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001603/0.7278, allocations: 0.7483 MB / 0.6837 GB, free: 10.67 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001154/0.7279, allocations: 301.1 kB / 0.684 GB, free: 10.38 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005044/0.733, allocations: 2.284 MB / 0.6862 GB, free: 8.082 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005314/0.7383, allocations: 2.669 MB / 0.6888 GB, free: 5.32 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01051/0.7489, allocations: 4.851 MB / 0.6936 GB, free: 400 kB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.402e-05/0.7489, allocations: 4 kB / 0.6936 GB, free: 396 kB / 494.1 MB
Notification: Performance of matching and sorting (n=531) (initialization): time 0.01744/0.7664, allocations: 7.224 MB / 0.7006 GB, free: 9.148 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 2.566e-05/0.7664, allocations: 8.688 kB / 0.7006 GB, free: 9.141 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.576e-05/0.7664, allocations: 4 kB / 0.7006 GB, free: 9.137 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01969/0.7861, allocations: 5.749 MB / 0.7063 GB, free: 3.383 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00367/0.7898, allocations: 0.6632 MB / 0.7069 GB, free: 2.719 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01435/0.8042, allocations: 5.45 MB / 0.7122 GB, free: 13.24 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006219/0.8105, allocations: 0.504 MB / 0.7127 GB, free: 12.73 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008935/0.8114, allocations: 140 kB / 0.7129 GB, free: 12.6 MB / 0.5137 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 23
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (410):
 * Single equations (assignments): 409
 * 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
   {(62,60)}
Notification: Performance of prepare postOptimizeDAE: time 0.001481/0.8129, allocations: 0.5245 MB / 0.7134 GB, free: 12.06 MB / 0.5137 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001029/0.8139, allocations: 335.7 kB / 0.7137 GB, free: 11.73 MB / 0.5137 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03544/0.8494, allocations: 10.51 MB / 0.724 GB, free: 1.141 MB / 0.5137 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.5e-05/0.8495, allocations: 31.92 kB / 0.724 GB, free: 1.109 MB / 0.5137 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.34e-05/0.8495, allocations: 0 / 0.724 GB, free: 1.109 MB / 0.5137 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.05e-05/0.8496, allocations: 15.95 kB / 0.724 GB, free: 1.094 MB / 0.5137 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.04297/0.8926, allocations: 14.65 MB / 0.7383 GB, free: 2.164 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.099e-05/0.8926, allocations: 0 / 0.7383 GB, free: 2.164 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001788/0.8944, allocations: 267.6 kB / 0.7386 GB, free: 1.902 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01689/0.9113, allocations: 4.765 MB / 0.7432 GB, free: 13.13 MB / 0.545 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0007954/0.9122, allocations: 51.98 kB / 0.7433 GB, free: 13.08 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.05597/0.9682, allocations: 21.49 MB / 0.7642 GB, free: 7.434 MB / 0.5606 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.687e-06/0.9682, allocations: 0 / 0.7642 GB, free: 7.434 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.3539/1.322, allocations: 12.81 MB / 0.7767 GB, free: 162.3 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00169/1.324, allocations: 499.6 kB / 0.7772 GB, free: 162.2 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001371/1.325, allocations: 43.16 kB / 0.7773 GB, free: 162.2 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00382/1.329, allocations: 252.4 kB / 0.7775 GB, free: 162.2 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001953/1.331, allocations: 0.517 MB / 0.778 GB, free: 162.2 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002388/1.331, allocations: 61.41 kB / 0.7781 GB, free: 162.2 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.00301/1.334, allocations: 1.876 MB / 0.7799 GB, free: 162.1 MB / 0.5606 GB
Notification: Performance of sort global known variables: time 1.91e-07/1.334, allocations: 0 / 0.7799 GB, free: 162.1 MB / 0.5606 GB
Notification: Performance of remove unused functions: time 0.004451/1.339, allocations: 1.129 MB / 0.781 GB, free: 162.1 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 20 (g1.gENSAL.w,g1.gENSAL.delta,g1.gENSAL.Epq,g1.gENSAL.PSIkd,g1.gENSAL.PSIppq,g2.gENSAL.w,g2.gENSAL.delta,g2.gENSAL.Epq,g2.gENSAL.PSIkd,g2.gENSAL.PSIppq,g3.gENSAL.w,g3.gENSAL.delta,g3.gENSAL.Epq,g3.gENSAL.PSIkd,g3.gENSAL.PSIppq,g4.gENSAL.w,g4.gENSAL.delta,g4.gENSAL.Epq,g4.gENSAL.PSIkd,g4.gENSAL.PSIppq)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (156):
 * Single equations (assignments): 155
 * 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
   {(54,52)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.04101/1.38, allocations: 13.23 MB / 0.7939 GB, free: 158.9 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.02383/1.404, allocations: 6.223 MB / 0.8 GB, free: 157 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 6.673e-06/1.404, allocations: 0 / 0.8 GB, free: 157 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.0129/1.417, allocations: 3.797 MB / 0.8037 GB, free: 155.8 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003609/1.42, allocations: 270.4 kB / 0.804 GB, free: 155.6 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.08877/1.509, allocations: 43.25 MB / 0.8462 GB, free: 120 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001926/1.511, allocations: 1.841 MB / 0.848 GB, free: 118.2 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.001872/1.513, allocations: 296.7 kB / 0.8483 GB, free: 117.9 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002479/1.515, allocations: 1.39 MB / 0.8496 GB, free: 116.5 MB / 0.5606 GB
Notification: Performance of SimCode: time 1.744e-06/1.515, allocations: 0 / 0.8496 GB, free: 116.5 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1313/1.647, allocations: 88.6 MB / 0.9362 GB, free: 28.02 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate platform static: time 13.99/15.64, allocations: 6.5 kB / 0.9362 GB, free: 28.02 MB / 0.5606 GB
(rm -f OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.sim & python3 -m fmpy simulate  --output-file OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_res.csv --start-time 0 --stop-time 10 --timeout 50 --relative-tolerance 0.001 --interface-type ModelExchange --solver CVode --output-interval 0.004 OpenIPSL_dev_OpenIPSL_Examples_TwoAreas_Two_Areas_PSSE.fmu > OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe 2>&1)