Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Tests.Controls.PSSE.ES.EXAC1.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.001647/0.001647, allocations: 99.64 kB / 18.95 MB, free: 2.668 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.592/1.592, allocations: 230.6 MB / 252.7 MB, free: 14.29 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.001847/0.001847, allocations: 208.6 kB / 309.2 MB, free: 3.113 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo): time 0.6901/0.6901, allocations: 107.2 MB / 472.8 MB, free: 29.76 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/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: translateModel(OpenIPSL.Tests.Controls.PSSE.ES.EXAC1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Controls.PSSE.ES.EXAC1")
translateModel(OpenIPSL.Tests.Controls.PSSE.ES.EXAC1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Controls.PSSE.ES.EXAC1") [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.002568/0.002568, allocations: 85.5 kB / 0.6335 GB, free: 29.48 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1485/0.151, allocations: 80.15 MB / 0.7118 GB, free: 7.172 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.ES.EXAC1): time 0.01099/0.162, allocations: 7.863 MB / 0.7195 GB, free: 15.27 MB / 0.4981 GB
Notification: Performance of NFInst.instExpressions: time 0.006954/0.169, allocations: 2.789 MB / 0.7222 GB, free: 12.48 MB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001756/0.1707, allocations: 19.94 kB / 0.7222 GB, free: 12.46 MB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.001299/0.172, allocations: 345 kB / 0.7226 GB, free: 12.12 MB / 0.4981 GB
Notification: Performance of NFTyping.typeBindings: time 0.003741/0.1758, allocations: 1.086 MB / 0.7236 GB, free: 11.03 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002668/0.1784, allocations: 0.9735 MB / 0.7246 GB, free: 10.06 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.004333/0.1828, allocations: 2.521 MB / 0.727 GB, free: 7.535 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0007696/0.1835, allocations: 347 kB / 0.7274 GB, free: 7.156 MB / 0.4981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001986/0.1855, allocations: 1.027 MB / 0.7284 GB, free: 6.125 MB / 0.4981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001863/0.1874, allocations: 1.124 MB / 0.7295 GB, free: 4.996 MB / 0.4981 GB
Notification: Performance of NFPackage.collectConstants: time 0.0004244/0.1878, allocations: 216 kB / 0.7297 GB, free: 4.785 MB / 0.4981 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001061/0.1889, allocations: 435.7 kB / 0.7301 GB, free: 4.359 MB / 0.4981 GB
Notification: Performance of NFScalarize.scalarize: time 0.0005493/0.1894, allocations: 0.5059 MB / 0.7306 GB, free: 3.852 MB / 0.4981 GB
Notification: Performance of NFVerifyModel.verify: time 0.0009985/0.1904, allocations: 0.8354 MB / 0.7314 GB, free: 3.012 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.003953/0.1944, allocations: 2.692 MB / 0.734 GB, free: 316 kB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 5.821e-06/0.1944, allocations: 4 kB / 0.734 GB, free: 312 kB / 0.4981 GB
Notification: Performance of FrontEnd: time 1.924e-06/0.1944, allocations: 4 kB / 0.734 GB, free: 308 kB / 0.4981 GB
Notification: Performance of Transformations before backend: time 6.165e-05/0.1945, allocations: 0 / 0.734 GB, free: 308 kB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 277
 * Number of variables: 277
Notification: Performance of Generate backend data structure: time 0.01495/0.2094, allocations: 4.003 MB / 0.7379 GB, free: 12.25 MB / 0.5137 GB
Notification: Performance of prepare preOptimizeDAE: time 4.844e-05/0.2095, allocations: 8.031 kB / 0.7379 GB, free: 12.24 MB / 0.5137 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00487/0.2143, allocations: 0.7793 MB / 0.7387 GB, free: 11.46 MB / 0.5137 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002616/0.2169, allocations: 1.192 MB / 0.7399 GB, free: 10.23 MB / 0.5137 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0008283/0.2178, allocations: 0.7762 MB / 0.7406 GB, free: 9.305 MB / 0.5137 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002806/0.218, allocations: 124 kB / 0.7407 GB, free: 9.184 MB / 0.5137 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0037/0.2217, allocations: 1.595 MB / 0.7423 GB, free: 7.539 MB / 0.5137 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.892e-05/0.2218, allocations: 0 / 0.7423 GB, free: 7.539 MB / 0.5137 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001417/0.2219, allocations: 44 kB / 0.7423 GB, free: 7.496 MB / 0.5137 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.719e-05/0.2219, allocations: 28 kB / 0.7424 GB, free: 7.469 MB / 0.5137 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003766/0.2257, allocations: 1.492 MB / 0.7438 GB, free: 5.969 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.01045/0.2362, allocations: 4.221 MB / 0.748 GB, free: 1.664 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.009094/0.2453, allocations: 2.568 MB / 0.7505 GB, free: 15.07 MB / 0.5294 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002405/0.2477, allocations: 0.916 MB / 0.7514 GB, free: 14.14 MB / 0.5294 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003526/0.248, allocations: 53.28 kB / 0.7514 GB, free: 14.09 MB / 0.5294 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002732/0.2507, allocations: 1.149 MB / 0.7525 GB, free: 12.92 MB / 0.5294 GB
Notification: Performance of pre-optimization done (n=136): time 3.977e-06/0.2508, allocations: 0 / 0.7525 GB, free: 12.92 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=136): time 0.04486/0.2956, allocations: 10.19 MB / 0.7625 GB, free: 2.707 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.676e-05/0.2957, allocations: 120.1 kB / 0.7626 GB, free: 2.562 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00293/0.2986, allocations: 1.495 MB / 0.7641 GB, free: 1.055 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0004743/0.2991, allocations: 61.77 kB / 0.7641 GB, free: 0.9883 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0006665/0.2998, allocations: 0.6874 MB / 0.7648 GB, free: 300 kB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0008044/0.3006, allocations: 0.5112 MB / 0.7653 GB, free: 15.78 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007409/0.3013, allocations: 367.5 kB / 0.7656 GB, free: 15.41 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0001344/0.3015, allocations: 305.1 kB / 0.7659 GB, free: 15.11 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002772/0.3042, allocations: 1.07 MB / 0.767 GB, free: 14.04 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002918/0.3072, allocations: 1.286 MB / 0.7682 GB, free: 12.68 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005609/0.3128, allocations: 2.263 MB / 0.7704 GB, free: 10.36 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.532e-05/0.3128, allocations: 4 kB / 0.7704 GB, free: 10.36 MB / 0.545 GB
Notification: Performance of matching and sorting (n=257) (initialization): time 0.01171/0.3245, allocations: 3.946 MB / 0.7743 GB, free: 6.398 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 4.132e-05/0.3245, allocations: 8 kB / 0.7743 GB, free: 6.391 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.104e-05/0.3246, allocations: 4 kB / 0.7743 GB, free: 6.387 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01121/0.3358, allocations: 2.841 MB / 0.7771 GB, free: 3.539 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002017/0.3378, allocations: 331.5 kB / 0.7774 GB, free: 3.215 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008464/0.3462, allocations: 3.379 MB / 0.7807 GB, free: 15.78 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003512/0.3498, allocations: 274.7 kB / 0.781 GB, free: 15.51 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003403/0.3501, allocations: 71.98 kB / 0.781 GB, free: 15.44 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 18
 * Number of states: 0 ()
 * Number of discrete variables: 3 ($whenCondition2,$whenCondition1,eXAC1.rotatingExciterWithDemagnetizationLimited.sISO.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (193):
 * Single equations (assignments): 191
 * Array equations: 0
 * Algorithm blocks: 0
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(30,26), (1,9)}
Notification: Performance of prepare postOptimizeDAE: time 0.00103/0.3511, allocations: 331.3 kB / 0.7813 GB, free: 15.11 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005087/0.3516, allocations: 182.8 kB / 0.7815 GB, free: 14.93 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01794/0.3696, allocations: 5.004 MB / 0.7864 GB, free: 9.902 MB / 0.5606 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.518e-05/0.3696, allocations: 12 kB / 0.7864 GB, free: 9.891 MB / 0.5606 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.129e-05/0.3696, allocations: 8.094 kB / 0.7864 GB, free: 9.883 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.472e-05/0.3696, allocations: 11.91 kB / 0.7864 GB, free: 9.871 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.02113/0.3908, allocations: 6.338 MB / 0.7926 GB, free: 3.477 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.407e-05/0.3908, allocations: 3.984 kB / 0.7926 GB, free: 3.473 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001137/0.3919, allocations: 167.8 kB / 0.7928 GB, free: 3.309 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.00827/0.4002, allocations: 2.2 MB / 0.7949 GB, free: 1.098 MB / 0.5606 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002784/0.4005, allocations: 31.98 kB / 0.795 GB, free: 1.066 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02842/0.4289, allocations: 9.82 MB / 0.8046 GB, free: 7.145 MB / 0.5762 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.343e-06/0.4289, allocations: 11.88 kB / 0.8046 GB, free: 7.133 MB / 0.5762 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02131/0.4502, allocations: 6.214 MB / 0.8106 GB, free: 0.8906 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007578/0.451, allocations: 214.4 kB / 0.8108 GB, free: 0.6797 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003805/0.4513, allocations: 31.98 kB / 0.8109 GB, free: 0.6484 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001812/0.4532, allocations: 147.8 kB / 0.811 GB, free: 0.5039 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000932/0.4541, allocations: 301.2 kB / 0.8113 GB, free: 216 kB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001185/0.4542, allocations: 35.98 kB / 0.8113 GB, free: 180 kB / 0.5762 GB
Notification: Performance of sorting global known variables: time 0.3752/0.8294, allocations: 1.01 MB / 0.8123 GB, free: 92.73 MB / 0.5762 GB
Notification: Performance of sort global known variables: time 7.62e-07/0.8294, allocations: 0 / 0.8123 GB, free: 92.73 MB / 0.5762 GB
Notification: Performance of remove unused functions: time 0.004164/0.8336, allocations: 0.5786 MB / 0.8129 GB, free: 92.73 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,gENCLS.delta,gENCLS.eq,gENROE.w,gENROE.delta,gENROE.Epd,gENROE.Epq,gENROE.PSIkd,gENROE.PSIkq,eXAC1.imLimitedSimpleLag.state,eXAC1.imDerivativeLag.x,eXAC1.leadLag.TF.x_scaled[1],eXAC1.rotatingExciterWithDemagnetizationLimited.sISO.y,eXAC1.TransducerDelay.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 (93):
 * Single equations (assignments): 92
 * 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.0008559/0.8344, allocations: 164.4 kB / 0.813 GB, free: 92.64 MB / 0.5762 GB
Notification: Performance of simCode: created initialization part: time 0.01948/0.8539, allocations: 2.927 MB / 0.8159 GB, free: 92.58 MB / 0.5762 GB
Notification: Performance of simCode: created event and clocks part: time 1.101e-05/0.8539, allocations: 0.9688 kB / 0.8159 GB, free: 92.58 MB / 0.5762 GB
Notification: Performance of simCode: created simulation system equations: time 0.01306/0.867, allocations: 1.974 MB / 0.8178 GB, free: 92.31 MB / 0.5762 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002256/0.8692, allocations: 155.4 kB / 0.818 GB, free: 92.21 MB / 0.5762 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.0114/0.8806, allocations: 3.413 MB / 0.8213 GB, free: 90.48 MB / 0.5762 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001376/0.882, allocations: 0.7061 MB / 0.822 GB, free: 90.32 MB / 0.5762 GB
Notification: Performance of simCode: alias equations: time 0.001542/0.8835, allocations: 163.9 kB / 0.8222 GB, free: 90.29 MB / 0.5762 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0007394/0.8843, allocations: 105.7 kB / 0.8223 GB, free: 90.27 MB / 0.5762 GB
Notification: Performance of SimCode: time 1.383e-06/0.8843, allocations: 0.5 kB / 0.8223 GB, free: 90.27 MB / 0.5762 GB
Notification: Performance of Templates: time 0.05723/0.9415, allocations: 35.08 MB / 0.8565 GB, free: 68.16 MB / 0.5762 GB
"
[Timeout remaining time 659]
make -j1 -f OpenIPSL_OpenIPSL.Tests.Controls.PSSE.ES.EXAC1.makefile [Timeout 660]
(rm -f OpenIPSL_OpenIPSL.Tests.Controls.PSSE.ES.EXAC1.pipe ; mkfifo OpenIPSL_OpenIPSL.Tests.Controls.PSSE.ES.EXAC1.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Tests.Controls.PSSE.ES.EXAC1.pipe >> ../files/OpenIPSL_OpenIPSL.Tests.Controls.PSSE.ES.EXAC1.sim & ./OpenIPSL_OpenIPSL.Tests.Controls.PSSE.ES.EXAC1  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Tests.Controls.PSSE.ES.EXAC1.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 8.100058754906058]
Failed to read output from testmodel.py, exit status != 0:
0.9500058200210333 0.959892866 0.76303445
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f9eb607efe0>