Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESST4B.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: translateModel(OpenIPSL.Tests.Controls.PSSE.ES.ESST4B,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESST4B")
translateModel(OpenIPSL.Tests.Controls.PSSE.ES.ESST4B,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESST4B")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001329/0.001329, allocations: 110.5 kB / 16.42 MB, free: 6.504 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.33/1.33, allocations: 222.9 MB / 240.1 MB, free: 4.547 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.001437/0.001437, allocations: 188.5 kB / 290.4 MB, free: 2.266 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5047/0.5047, allocations: 91.91 MB / 432.4 MB, free: 9.191 MB / 302.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001442/0.001442, allocations: 77.62 kB / 0.5734 GB, free: 33.64 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1008/0.1023, allocations: 71.29 MB / 0.643 GB, free: 3.59 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.ES.ESST4B): time 0.00845/0.1107, allocations: 8.927 MB / 0.6518 GB, free: 10.62 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.004798/0.1156, allocations: 2.904 MB / 0.6546 GB, free: 7.707 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0006354/0.1162, allocations: 16 kB / 0.6546 GB, free: 7.691 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0006388/0.1169, allocations: 337 kB / 0.6549 GB, free: 7.359 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002384/0.1193, allocations: 1.207 MB / 0.6561 GB, free: 6.148 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001845/0.1211, allocations: 1.005 MB / 0.6571 GB, free: 5.156 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002364/0.1235, allocations: 2.459 MB / 0.6595 GB, free: 2.688 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0006561/0.1242, allocations: 0.5308 MB / 0.66 GB, free: 2.125 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0009881/0.1252, allocations: 0.9923 MB / 0.661 GB, free: 1.129 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001181/0.1264, allocations: 1.221 MB / 0.6622 GB, free: 15.9 MB / 478.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002956/0.1267, allocations: 212 kB / 0.6624 GB, free: 15.7 MB / 478.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0007291/0.1274, allocations: 439.7 kB / 0.6628 GB, free: 15.27 MB / 478.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003816/0.1278, allocations: 0.5098 MB / 0.6633 GB, free: 14.75 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0007749/0.1286, allocations: 1.022 MB / 0.6643 GB, free: 13.73 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.00238/0.131, allocations: 2.626 MB / 0.6669 GB, free: 11.09 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.03e-06/0.131, allocations: 0 / 0.6669 GB, free: 11.09 MB / 478.1 MB
Notification: Performance of FrontEnd: time 1.563e-06/0.131, allocations: 0 / 0.6669 GB, free: 11.09 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 2.95e-05/0.131, allocations: 0 / 0.6669 GB, free: 11.09 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: 291
 * Number of variables: 291
Notification: Performance of Generate backend data structure: time 0.01239/0.1434, allocations: 3.905 MB / 0.6707 GB, free: 7.121 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.843e-05/0.1435, allocations: 8.031 kB / 0.6707 GB, free: 7.113 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005493/0.149, allocations: 0.9431 MB / 0.6716 GB, free: 6.168 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001988/0.151, allocations: 1.22 MB / 0.6728 GB, free: 4.898 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005303/0.1515, allocations: 0.5007 MB / 0.6733 GB, free: 4.316 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002391/0.1518, allocations: 132 kB / 0.6734 GB, free: 4.188 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003369/0.1552, allocations: 1.695 MB / 0.6751 GB, free: 2.43 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.563e-05/0.1552, allocations: 0 / 0.6751 GB, free: 2.43 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001359/0.1553, allocations: 48 kB / 0.6751 GB, free: 2.383 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.261e-05/0.1554, allocations: 28 kB / 0.6751 GB, free: 2.355 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003522/0.1589, allocations: 1.555 MB / 0.6766 GB, free: 0.793 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.01004/0.1689, allocations: 5.894 MB / 0.6824 GB, free: 10.63 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.009797/0.1788, allocations: 3.411 MB / 0.6857 GB, free: 7.078 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002197/0.181, allocations: 1.022 MB / 0.6867 GB, free: 6.051 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001636/0.1812, allocations: 33.27 kB / 0.6868 GB, free: 6.016 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.557e-05/0.1812, allocations: 47.81 kB / 0.6868 GB, free: 5.961 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=136): time 3.216e-06/0.1812, allocations: 0 / 0.6868 GB, free: 5.961 MB / 494.1 MB
Notification: Performance of matching and sorting (n=136): time 0.04258/0.2238, allocations: 10.57 MB / 0.6971 GB, free: 11.34 MB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.296e-05/0.2239, allocations: 201.2 kB / 0.6973 GB, free: 11.09 MB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002421/0.2264, allocations: 1.46 MB / 0.6987 GB, free: 9.625 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003468/0.2267, allocations: 57.7 kB / 0.6988 GB, free: 9.562 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005786/0.2273, allocations: 0.6718 MB / 0.6995 GB, free: 8.883 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006919/0.228, allocations: 0.5152 MB / 0.7 GB, free: 8.363 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0008767/0.2289, allocations: 371.6 kB / 0.7003 GB, free: 7.992 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 8.205e-05/0.229, allocations: 313.1 kB / 0.7006 GB, free: 7.684 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002634/0.2316, allocations: 1.114 MB / 0.7017 GB, free: 6.559 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002804/0.2344, allocations: 1.368 MB / 0.703 GB, free: 5.109 MB / 0.4981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005205/0.2397, allocations: 2.35 MB / 0.7053 GB, free: 2.703 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.958e-06/0.2397, allocations: 7.938 kB / 0.7053 GB, free: 2.695 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=261) (initialization): time 0.01173/0.2514, allocations: 4.36 MB / 0.7096 GB, free: 14.32 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 2.446e-05/0.2515, allocations: 11.94 kB / 0.7096 GB, free: 14.31 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.193e-05/0.2515, allocations: 4 kB / 0.7096 GB, free: 14.31 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01317/0.2647, allocations: 3.578 MB / 0.7131 GB, free: 10.72 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00143/0.2661, allocations: 295.6 kB / 0.7134 GB, free: 10.43 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0083/0.2744, allocations: 3.447 MB / 0.7168 GB, free: 6.957 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002433/0.2769, allocations: 290.5 kB / 0.717 GB, free: 6.672 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002305/0.2771, allocations: 71.97 kB / 0.7171 GB, free: 6.602 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003016/0.2801, allocations: 1.3 MB / 0.7184 GB, free: 5.293 MB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002656/0.2828, allocations: 1.333 MB / 0.7197 GB, free: 3.879 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.00493/0.2878, allocations: 2.295 MB / 0.7219 GB, free: 1.527 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 5.34e-06/0.2878, allocations: 0 / 0.7219 GB, free: 1.527 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=261) (initialization_lambda0): time 0.01131/0.2991, allocations: 4.251 MB / 0.7261 GB, free: 13.27 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 1.798e-05/0.2991, allocations: 9.25 kB / 0.7261 GB, free: 13.26 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.049e-05/0.2991, allocations: 4 kB / 0.7261 GB, free: 13.25 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01282/0.312, allocations: 3.543 MB / 0.7295 GB, free: 9.703 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00151/0.3135, allocations: 295.5 kB / 0.7298 GB, free: 9.414 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008558/0.3221, allocations: 3.338 MB / 0.7331 GB, free: 6.047 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002454/0.3246, allocations: 262.9 kB / 0.7333 GB, free: 5.789 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002832/0.3249, allocations: 67.98 kB / 0.7334 GB, free: 5.723 MB / 0.5294 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 (eSST4B.VM1.local_reset,eSST4B.VR1.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (169):
 * Single equations (assignments): 168
 * 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
   {(35,58)}
Notification: Performance of prepare postOptimizeDAE: time 0.0005732/0.3255, allocations: 273 kB / 0.7337 GB, free: 5.449 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005459/0.326, allocations: 178.8 kB / 0.7338 GB, free: 5.273 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01678/0.3428, allocations: 5.118 MB / 0.7388 GB, free: 132 kB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.736e-05/0.3429, allocations: 11.97 kB / 0.7388 GB, free: 120 kB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.999e-06/0.3429, allocations: 7.938 kB / 0.7388 GB, free: 112 kB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.000589/0.3435, allocations: 291.6 kB / 0.7391 GB, free: 15.8 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004668/0.344, allocations: 28 kB / 0.7391 GB, free: 15.77 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001886/0.3459, allocations: 147.8 kB / 0.7393 GB, free: 15.63 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001663/0.3475, allocations: 0.5785 MB / 0.7399 GB, free: 15.05 MB / 0.545 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.009759/0.3573, allocations: 3.199 MB / 0.743 GB, free: 11.84 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.007143/0.3645, allocations: 3.281 MB / 0.7462 GB, free: 8.539 MB / 0.545 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002082/0.3666, allocations: 0.8691 MB / 0.747 GB, free: 7.672 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.001527/0.3681, allocations: 0.9652 MB / 0.748 GB, free: 6.711 MB / 0.545 GB
Notification: Performance of Backend: time 1.753e-06/0.3681, allocations: 0 / 0.748 GB, free: 6.711 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.01625/0.3844, allocations: 4.735 MB / 0.7526 GB, free: 1.973 MB / 0.545 GB
Notification: Performance of SimCode: time 0.006115/0.3905, allocations: 4.032 MB / 0.7565 GB, free: 13.88 MB / 0.5606 GB
Notification: Performance of Templates: time 0.3053/0.6959, allocations: 44.31 MB / 0.7998 GB, free: 102.4 MB / 0.5606 GB
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESST4B.makefile
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESST4B.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESST4B.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESST4B.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESST4B.sim & ./OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESST4B  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.ESST4B.pipe 2>&1)