Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC1A.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.Tests.Controls.PSSE.ES.ESDC1A,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Tests_Controls_PSSE_ES_ESDC1A",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Tests.Controls.PSSE.ES.ESDC1A,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Tests_Controls_PSSE_ES_ESDC1A",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.007618/0.007618, allocations: 99.8 kB / 15.73 MB, free: 6.004 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.374/1.374, allocations: 222.9 MB / 239.4 MB, free: 4.551 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.001469/0.001469, allocations: 196.4 kB / 289.7 MB, free: 2.262 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5201/0.5201, allocations: 91.9 MB / 431.8 MB, free: 9.172 MB / 302.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001458/0.001458, allocations: 81.56 kB / 0.5723 GB, free: 33.6 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1174/0.1188, allocations: 71.29 MB / 0.6419 GB, free: 4.07 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.ES.ESDC1A): time 0.009017/0.1279, allocations: 8.756 MB / 0.6504 GB, free: 11.27 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.0061/0.134, allocations: 2.848 MB / 0.6532 GB, free: 8.41 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001478/0.1356, allocations: 15.94 kB / 0.6532 GB, free: 8.395 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001002/0.1366, allocations: 345.1 kB / 0.6536 GB, free: 8.055 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003137/0.1398, allocations: 1.091 MB / 0.6546 GB, free: 6.961 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002245/0.142, allocations: 0.9497 MB / 0.6556 GB, free: 6.016 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00335/0.1454, allocations: 2.422 MB / 0.6579 GB, free: 3.59 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0007653/0.1462, allocations: 486.1 kB / 0.6584 GB, free: 3.086 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001443/0.1476, allocations: 0.9572 MB / 0.6593 GB, free: 2.125 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001441/0.1491, allocations: 1.202 MB / 0.6605 GB, free: 0.918 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003247/0.1494, allocations: 204 kB / 0.6607 GB, free: 0.7188 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0008981/0.1503, allocations: 475.7 kB / 0.6611 GB, free: 260 kB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0004078/0.1507, allocations: 0.4981 MB / 0.6616 GB, free: 15.75 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0008814/0.1516, allocations: 0.9828 MB / 0.6626 GB, free: 14.77 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.002824/0.1545, allocations: 2.564 MB / 0.6651 GB, free: 12.19 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.093e-06/0.1545, allocations: 3.984 kB / 0.6651 GB, free: 12.19 MB / 478.1 MB
Notification: Performance of FrontEnd: time 1.754e-06/0.1545, allocations: 0 / 0.6651 GB, free: 12.19 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 3.482e-05/0.1545, allocations: 0 / 0.6651 GB, free: 12.19 MB / 478.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 263
 * Number of variables: 263
Notification: Performance of Generate backend data structure: time 0.01252/0.1671, allocations: 3.826 MB / 0.6688 GB, free: 8.297 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.457e-05/0.1671, allocations: 8.031 kB / 0.6688 GB, free: 8.289 MB / 478.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0002167/0.1673, allocations: 164.5 kB / 0.669 GB, free: 8.117 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004641/0.172, allocations: 0.7754 MB / 0.6698 GB, free: 7.34 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002626/0.1746, allocations: 1.228 MB / 0.671 GB, free: 6.062 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005151/0.1752, allocations: 470.4 kB / 0.6714 GB, free: 5.527 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002339/0.1754, allocations: 120 kB / 0.6715 GB, free: 5.41 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003084/0.1785, allocations: 1.527 MB / 0.673 GB, free: 3.832 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.513e-05/0.1785, allocations: 0 / 0.673 GB, free: 3.832 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001228/0.1787, allocations: 40 kB / 0.673 GB, free: 3.793 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.468e-05/0.1787, allocations: 28 kB / 0.6731 GB, free: 3.766 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003226/0.1819, allocations: 1.421 MB / 0.6745 GB, free: 2.336 MB / 478.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.00984/0.1918, allocations: 5.403 MB / 0.6797 GB, free: 12.7 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.008748/0.2006, allocations: 3.066 MB / 0.6827 GB, free: 9.492 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002074/0.2027, allocations: 0.9629 MB / 0.6837 GB, free: 8.523 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005926/0.2033, allocations: 0.7297 MB / 0.6844 GB, free: 7.625 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002256/0.2055, allocations: 1.118 MB / 0.6855 GB, free: 6.484 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=133): time 2.986e-06/0.2056, allocations: 0 / 0.6855 GB, free: 6.484 MB / 494.1 MB
Notification: Performance of matching and sorting (n=133): time 0.03928/0.2448, allocations: 9.769 MB / 0.695 GB, free: 12.68 MB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.666e-05/0.245, allocations: 124.1 kB / 0.6951 GB, free: 12.53 MB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002457/0.2474, allocations: 1.551 MB / 0.6967 GB, free: 10.97 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003377/0.2478, allocations: 53.7 kB / 0.6967 GB, free: 10.91 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004969/0.2483, allocations: 0.6524 MB / 0.6973 GB, free: 10.26 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0005534/0.2488, allocations: 0.4957 MB / 0.6978 GB, free: 9.758 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0006645/0.2495, allocations: 379.6 kB / 0.6982 GB, free: 9.379 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 9.316e-05/0.2496, allocations: 301.1 kB / 0.6985 GB, free: 9.082 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002282/0.2519, allocations: 1.046 MB / 0.6995 GB, free: 8.031 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002429/0.2543, allocations: 1.266 MB / 0.7007 GB, free: 6.703 MB / 0.4981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.00463/0.259, allocations: 2.231 MB / 0.7029 GB, free: 4.414 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.024e-06/0.259, allocations: 3.938 kB / 0.7029 GB, free: 4.41 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=261) (initialization): time 0.009396/0.2684, allocations: 3.844 MB / 0.7067 GB, free: 0.5547 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 2.856e-05/0.2685, allocations: 12.72 kB / 0.7067 GB, free: 0.543 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.715e-05/0.2685, allocations: 4 kB / 0.7067 GB, free: 0.5391 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009488/0.278, allocations: 2.675 MB / 0.7093 GB, free: 13.86 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00186/0.2799, allocations: 363.5 kB / 0.7096 GB, free: 13.5 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006291/0.2862, allocations: 2.695 MB / 0.7123 GB, free: 10.77 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002534/0.2888, allocations: 274.6 kB / 0.7125 GB, free: 10.5 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001959/0.289, allocations: 71.98 kB / 0.7126 GB, free: 10.43 MB / 0.5137 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 17
 * Number of states: 0 ()
 * Number of discrete variables: 3 ($whenCondition2,$whenCondition1,eSDC1A.rotatingExciterLimited.sISO.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (204):
 * Single equations (assignments): 202
 * 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.0006737/0.2897, allocations: 325.3 kB / 0.7129 GB, free: 10.11 MB / 0.5137 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003495/0.29, allocations: 186.8 kB / 0.7131 GB, free: 9.922 MB / 0.5137 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01609/0.3061, allocations: 4.946 MB / 0.7179 GB, free: 4.953 MB / 0.5137 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.949e-05/0.3062, allocations: 12 kB / 0.7179 GB, free: 4.941 MB / 0.5137 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.477e-06/0.3062, allocations: 4 kB / 0.7179 GB, free: 4.938 MB / 0.5137 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.047e-05/0.3062, allocations: 11.97 kB / 0.718 GB, free: 4.926 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.01958/0.3258, allocations: 7.12 MB / 0.7249 GB, free: 13.68 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.877e-05/0.3259, allocations: 3.938 kB / 0.7249 GB, free: 13.67 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001136/0.327, allocations: 167.8 kB / 0.7251 GB, free: 13.51 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.007385/0.3344, allocations: 2.138 MB / 0.7272 GB, free: 11.37 MB / 0.5294 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002304/0.3347, allocations: 35.92 kB / 0.7272 GB, free: 11.33 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0249/0.3596, allocations: 10.07 MB / 0.737 GB, free: 1.109 MB / 0.5294 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.745e-06/0.3596, allocations: 0 / 0.737 GB, free: 1.109 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01894/0.3786, allocations: 6.137 MB / 0.743 GB, free: 10.93 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007897/0.3794, allocations: 295.6 kB / 0.7433 GB, free: 10.62 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003133/0.3797, allocations: 24 kB / 0.7433 GB, free: 10.6 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001613/0.3813, allocations: 151.8 kB / 0.7435 GB, free: 10.45 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008362/0.3822, allocations: 273.3 kB / 0.7437 GB, free: 10.19 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001137/0.3823, allocations: 35.98 kB / 0.7438 GB, free: 10.15 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.001602/0.3839, allocations: 1.032 MB / 0.7448 GB, free: 9.125 MB / 0.545 GB
Notification: Performance of sort global known variables: time 1.71e-07/0.3839, allocations: 0 / 0.7448 GB, free: 9.125 MB / 0.545 GB
Notification: Performance of remove unused functions: time 0.001564/0.3855, allocations: 0.5697 MB / 0.7453 GB, free: 8.555 MB / 0.545 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,eSDC1A.imDerivativeLag.x,eSDC1A.imLeadLag.TF.x_scaled[1],eSDC1A.simpleLagLim.state,eSDC1A.rotatingExciterLimited.sISO.y,eSDC1A.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 (90):
 * Single equations (assignments): 89
 * 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.01912/0.4046, allocations: 6.763 MB / 0.7519 GB, free: 1.75 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.009035/0.4137, allocations: 3.042 MB / 0.7549 GB, free: 14.7 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 5.45e-06/0.4138, allocations: 4.062 kB / 0.7549 GB, free: 14.7 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.006125/0.4199, allocations: 1.988 MB / 0.7568 GB, free: 12.7 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001348/0.4213, allocations: 167.1 kB / 0.757 GB, free: 12.54 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3447/0.766, allocations: 21.97 MB / 0.7785 GB, free: 116.6 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007718/0.7668, allocations: 1.273 MB / 0.7797 GB, free: 115.9 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.0007915/0.7676, allocations: 156.4 kB / 0.7799 GB, free: 115.8 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008094/0.7684, allocations: 0.6614 MB / 0.7805 GB, free: 115.8 MB / 0.5606 GB
Notification: Performance of SimCode: time 8.02e-07/0.7684, allocations: 0 / 0.7805 GB, free: 115.8 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.06968/0.8381, allocations: 46.72 MB / 0.8261 GB, free: 77.65 MB / 0.5606 GB
Notification: Performance of buildModelFMU: Generate platform static: time 14.35/15.19, allocations: 12.06 kB / 0.8261 GB, free: 77.64 MB / 0.5606 GB
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC1A.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC1A.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC1A.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC1A.sim & python3 -m fmpy simulate  --output-file OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC1A_res.csv --start-time 0 --stop-time 10 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.004 OpenIPSL_dev_OpenIPSL_Tests_Controls_PSSE_ES_ESDC1A.fmu > OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESDC1A.pipe 2>&1)