Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC2A.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001305/0.001305, allocations: 113.5 kB / 19.73 MB, free: 2.242 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.467/1.467, allocations: 230.6 MB / 253.4 MB, free: 14.65 MB / 206.1 MB
"
[Timeout remaining time 178]
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.001786/0.001786, allocations: 211.8 kB / 310 MB, free: 3.066 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo): time 0.596/0.596, allocations: 101.3 MB / 467.8 MB, free: 30.57 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.1.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/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 Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 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: "" <> buildModelFMU(OpenIPSL.Tests.Controls.PSSE.ES.ESDC2A,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Tests_Controls_PSSE_ES_ESDC2A",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Tests.Controls.PSSE.ES.ESDC2A,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Tests_Controls_PSSE_ES_ESDC2A",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: OpenIPSL requested package Complex of version 4.0.0. Complex 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: OpenIPSL requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.00346/0.00346, allocations: 83.34 kB / 0.6252 GB, free: 33.32 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1571/0.1606, allocations: 78.61 MB / 0.702 GB, free: 1.066 MB / 478.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.ES.ESDC2A): time 0.01076/0.1713, allocations: 8.951 MB / 0.7107 GB, free: 8.066 MB / 494.1 MB
Notification: Performance of NFInst.instExpressions: time 0.006515/0.1778, allocations: 2.91 MB / 0.7135 GB, free: 5.152 MB / 494.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001529/0.1794, allocations: 19.94 kB / 0.7136 GB, free: 5.133 MB / 494.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001133/0.1805, allocations: 345 kB / 0.7139 GB, free: 4.793 MB / 494.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.00316/0.1836, allocations: 1.11 MB / 0.715 GB, free: 3.68 MB / 494.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002428/0.1861, allocations: 0.9676 MB / 0.7159 GB, free: 2.719 MB / 494.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004198/0.1903, allocations: 2.475 MB / 0.7183 GB, free: 236 kB / 494.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008178/0.1911, allocations: 342.8 kB / 0.7187 GB, free: 15.86 MB / 0.4981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001918/0.193, allocations: 1.004 MB / 0.7196 GB, free: 14.85 MB / 0.4981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001849/0.1949, allocations: 1.214 MB / 0.7208 GB, free: 13.63 MB / 0.4981 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003923/0.1952, allocations: 204 kB / 0.721 GB, free: 13.43 MB / 0.4981 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001009/0.1963, allocations: 483.6 kB / 0.7215 GB, free: 12.96 MB / 0.4981 GB
Notification: Performance of NFScalarize.scalarize: time 0.00048/0.1967, allocations: 0.5059 MB / 0.722 GB, free: 12.45 MB / 0.4981 GB
Notification: Performance of NFVerifyModel.verify: time 0.001053/0.1978, allocations: 0.9867 MB / 0.7229 GB, free: 11.46 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.003619/0.2014, allocations: 2.607 MB / 0.7255 GB, free: 8.844 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 4.679e-06/0.2014, allocations: 4 kB / 0.7255 GB, free: 8.84 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 1.533e-06/0.2014, allocations: 0 / 0.7255 GB, free: 8.84 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 5.614e-05/0.2015, allocations: 0 / 0.7255 GB, free: 8.84 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 271
 * Number of variables: 271
Notification: Performance of Generate backend data structure: time 0.01439/0.2159, allocations: 3.842 MB / 0.7292 GB, free: 4.953 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 5.241e-05/0.2159, allocations: 8.031 kB / 0.7292 GB, free: 4.945 MB / 0.4981 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 4.309e-05/0.216, allocations: 30 kB / 0.7293 GB, free: 4.914 MB / 0.4981 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0002584/0.2162, allocations: 172.8 kB / 0.7294 GB, free: 4.734 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004827/0.221, allocations: 0.7792 MB / 0.7302 GB, free: 3.953 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002628/0.2237, allocations: 1.172 MB / 0.7313 GB, free: 2.746 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00066/0.2243, allocations: 0.6351 MB / 0.732 GB, free: 1.992 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000301/0.2246, allocations: 124 kB / 0.7321 GB, free: 1.871 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00348/0.2281, allocations: 1.563 MB / 0.7336 GB, free: 264 kB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.258e-05/0.2281, allocations: 4 kB / 0.7336 GB, free: 260 kB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001367/0.2283, allocations: 40 kB / 0.7337 GB, free: 220 kB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.845e-05/0.2283, allocations: 28 kB / 0.7337 GB, free: 192 kB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00354/0.2318, allocations: 1.456 MB / 0.7351 GB, free: 14.72 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 preOpt removeSimpleEquations (simulation): time 0.0107/0.2425, allocations: 5.392 MB / 0.7404 GB, free: 9.094 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00884/0.2514, allocations: 2.513 MB / 0.7428 GB, free: 6.559 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002397/0.2538, allocations: 0.8886 MB / 0.7437 GB, free: 5.66 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003389/0.2541, allocations: 61.25 kB / 0.7437 GB, free: 5.602 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.00265/0.2568, allocations: 1.122 MB / 0.7448 GB, free: 4.457 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=134): time 2.915e-06/0.2568, allocations: 0 / 0.7448 GB, free: 4.457 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=134): time 0.0457/0.3025, allocations: 10.35 MB / 0.7549 GB, free: 10.09 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.95e-05/0.3026, allocations: 116.1 kB / 0.7551 GB, free: 9.945 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002561/0.3051, allocations: 1.54 MB / 0.7566 GB, free: 8.398 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003426/0.3055, allocations: 53.7 kB / 0.7566 GB, free: 8.34 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005873/0.306, allocations: 0.6641 MB / 0.7573 GB, free: 7.668 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006093/0.3067, allocations: 0.5035 MB / 0.7578 GB, free: 7.16 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007482/0.3074, allocations: 383.6 kB / 0.7581 GB, free: 6.777 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 0.0001277/0.3075, allocations: 301.1 kB / 0.7584 GB, free: 6.48 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002543/0.3101, allocations: 1.054 MB / 0.7594 GB, free: 5.422 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002798/0.3129, allocations: 1.284 MB / 0.7607 GB, free: 4.07 MB / 0.5294 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005118/0.318, allocations: 2.268 MB / 0.7629 GB, free: 1.738 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.506e-06/0.318, allocations: 0 / 0.7629 GB, free: 1.738 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=263) (initialization): time 0.01105/0.3291, allocations: 3.832 MB / 0.7666 GB, free: 13.89 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 2.908e-05/0.3291, allocations: 8 kB / 0.7667 GB, free: 13.89 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.719e-05/0.3291, allocations: 4 kB / 0.7667 GB, free: 13.88 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01034/0.3395, allocations: 2.731 MB / 0.7693 GB, free: 11.14 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001848/0.3413, allocations: 363.5 kB / 0.7697 GB, free: 10.79 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006992/0.3483, allocations: 2.717 MB / 0.7723 GB, free: 8.051 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002821/0.3511, allocations: 274.6 kB / 0.7726 GB, free: 7.781 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002295/0.3513, allocations: 75.98 kB / 0.7727 GB, free: 7.707 MB / 0.545 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 19
 * Number of states: 0 ()
 * Number of discrete variables: 2 ($whenCondition1,eSDC2A.rotatingExciterLimited.sISO.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (206):
 * Single equations (assignments): 204
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 1
 * 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
   {(30,26)}
Notification: Performance of prepare postOptimizeDAE: time 0.000788/0.3521, allocations: 333.3 kB / 0.773 GB, free: 7.371 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004419/0.3526, allocations: 186.8 kB / 0.7732 GB, free: 7.188 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01761/0.3702, allocations: 4.977 MB / 0.778 GB, free: 2.191 MB / 0.545 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.687e-05/0.3702, allocations: 16 kB / 0.778 GB, free: 2.176 MB / 0.545 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.02e-05/0.3702, allocations: 7.938 kB / 0.778 GB, free: 2.168 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.656e-05/0.3703, allocations: 11.97 kB / 0.778 GB, free: 2.156 MB / 0.545 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.02072/0.391, allocations: 7.123 MB / 0.785 GB, free: 10.9 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.119e-05/0.391, allocations: 7.984 kB / 0.785 GB, free: 10.89 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001103/0.3921, allocations: 171.7 kB / 0.7852 GB, free: 10.73 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.008201/0.4003, allocations: 2.2 MB / 0.7873 GB, free: 8.52 MB / 0.5606 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002779/0.4006, allocations: 27.98 kB / 0.7874 GB, free: 8.492 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02961/0.4302, allocations: 10.13 MB / 0.7972 GB, free: 14.24 MB / 0.5762 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.617e-06/0.4302, allocations: 0 / 0.7972 GB, free: 14.24 MB / 0.5762 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02092/0.4511, allocations: 6.132 MB / 0.8032 GB, free: 8.078 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.000673/0.4518, allocations: 226.4 kB / 0.8034 GB, free: 7.855 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005362/0.4523, allocations: 24 kB / 0.8035 GB, free: 7.832 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001984/0.4543, allocations: 147.8 kB / 0.8036 GB, free: 7.688 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009887/0.4553, allocations: 281.2 kB / 0.8039 GB, free: 7.414 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001886/0.4555, allocations: 43.98 kB / 0.8039 GB, free: 7.371 MB / 0.5762 GB
Notification: Performance of sorting global known variables: time 0.002113/0.4576, allocations: 1.021 MB / 0.8049 GB, free: 6.355 MB / 0.5762 GB
Notification: Performance of sort global known variables: time 8e-08/0.4576, allocations: 0 / 0.8049 GB, free: 6.355 MB / 0.5762 GB
Notification: Performance of remove unused functions: time 0.002046/0.4596, allocations: 0.5737 MB / 0.8055 GB, free: 5.781 MB / 0.5762 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 14 (gENCLS.omega,eSDC2A.simpleLagLimVar.state,eSDC2A.TransducerDelay.state,eSDC2A.rotatingExciterLimited.sISO.y,eSDC2A.imLeadLag.TF.x_scaled[1],eSDC2A.imDerivativeLag.x,gENROE.PSIkq,gENROE.PSIkd,gENROE.Epq,gENROE.Epd,gENROE.delta,gENROE.w,gENCLS.eq,gENCLS.delta)
 * Number of discrete variables: 1 ($whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (92):
 * Single equations (assignments): 91
 * 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
   {(26,23)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.3382/0.7978, allocations: 6.746 MB / 0.8121 GB, free: 100.8 MB / 0.5762 GB
Notification: Performance of simCode: created initialization part: time 0.009937/0.8077, allocations: 2.909 MB / 0.8149 GB, free: 100.7 MB / 0.5762 GB
Notification: Performance of simCode: created event and clocks part: time 6.693e-06/0.8077, allocations: 0.5625 kB / 0.8149 GB, free: 100.7 MB / 0.5762 GB
Notification: Performance of simCode: created simulation system equations: time 0.006446/0.8142, allocations: 1.959 MB / 0.8168 GB, free: 100.4 MB / 0.5762 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001329/0.8155, allocations: 163 kB / 0.817 GB, free: 100.3 MB / 0.5762 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.04558/0.8611, allocations: 19.93 MB / 0.8364 GB, free: 92.53 MB / 0.5762 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005702/0.8617, allocations: 1.282 MB / 0.8377 GB, free: 91.79 MB / 0.5762 GB
Notification: Performance of simCode: alias equations: time 0.0006896/0.8624, allocations: 160.6 kB / 0.8378 GB, free: 91.75 MB / 0.5762 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0007275/0.8631, allocations: 0.6814 MB / 0.8385 GB, free: 91.21 MB / 0.5762 GB
Notification: Performance of SimCode: time 9.92e-07/0.8631, allocations: 0 / 0.8385 GB, free: 91.21 MB / 0.5762 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.0707/0.9338, allocations: 48.63 MB / 0.886 GB, free: 44.8 MB / 0.5762 GB
Notification: Performance of buildModelFMU: Generate platform static: time 16.06/17, allocations: 8.938 kB / 0.886 GB, free: 44.8 MB / 0.5762 GB
"
[Timeout remaining time 643]
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC2A.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC2A.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC2A.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC2A.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --tempDir=temp_OpenIPSL_dev_OpenIPSL_Tests_Controls_PSSE_ES_ESDC2A_fmu --startTime=0 --stopTime=10 --stepSize=0.004 --timeout=50 --tolerance=1e-06 OpenIPSL_dev_OpenIPSL_Tests_Controls_PSSE_ES_ESDC2A.fmu > OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC2A.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 21.166153245954774]
Failed to read output from testmodel.py, exit status != 0:
17.203137958073057 17.219691603 17.014804652
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fa2713773a0>