Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.IEEET1.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.IEEET1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.IEEET1")
translateModel(OpenIPSL.Tests.Controls.PSSE.ES.IEEET1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.IEEET1")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001142/0.001143, allocations: 115.3 kB / 16.42 MB, free: 6.496 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.358/1.358, 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.001373/0.001373, allocations: 192.4 kB / 290.4 MB, free: 2.273 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4946/0.4946, allocations: 91.91 MB / 432.4 MB, free: 9.203 MB / 302.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.00162/0.00162, allocations: 79.97 kB / 0.5734 GB, free: 33.62 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1144/0.1161, allocations: 71.29 MB / 0.643 GB, free: 3.582 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.ES.IEEET1): time 0.009404/0.1255, allocations: 8.545 MB / 0.6514 GB, free: 10.99 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.005606/0.1312, allocations: 2.684 MB / 0.654 GB, free: 8.301 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001609/0.1328, allocations: 12 kB / 0.654 GB, free: 8.289 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001033/0.1339, allocations: 317.2 kB / 0.6543 GB, free: 7.977 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003148/0.1371, allocations: 1.053 MB / 0.6553 GB, free: 6.918 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001983/0.1391, allocations: 0.8423 MB / 0.6562 GB, free: 6.082 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002838/0.1419, allocations: 2.205 MB / 0.6583 GB, free: 3.871 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0007363/0.1427, allocations: 473.4 kB / 0.6588 GB, free: 3.379 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001203/0.1439, allocations: 0.8754 MB / 0.6596 GB, free: 2.5 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001447/0.1453, allocations: 1.132 MB / 0.6607 GB, free: 1.363 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.000479/0.1458, allocations: 188 kB / 0.6609 GB, free: 1.18 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.00103/0.1469, allocations: 467.7 kB / 0.6614 GB, free: 0.7227 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0005029/0.1474, allocations: 470.1 kB / 0.6618 GB, free: 268 kB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001107/0.1485, allocations: 0.9323 MB / 0.6627 GB, free: 15.32 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.002874/0.1514, allocations: 2.432 MB / 0.6651 GB, free: 12.88 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 3.897e-06/0.1514, allocations: 0 / 0.6651 GB, free: 12.88 MB / 478.1 MB
Notification: Performance of FrontEnd: time 1.483e-06/0.1514, allocations: 0 / 0.6651 GB, free: 12.88 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 6.763e-05/0.1515, allocations: 0 / 0.6651 GB, free: 12.88 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: 254
 * Number of variables: 254
Notification: Performance of Generate backend data structure: time 0.01245/0.1639, allocations: 3.662 MB / 0.6687 GB, free: 9.152 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.285e-05/0.164, allocations: 12.03 kB / 0.6687 GB, free: 9.141 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004537/0.1685, allocations: 0.7598 MB / 0.6694 GB, free: 8.379 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001761/0.1703, allocations: 1.13 MB / 0.6705 GB, free: 7.199 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003937/0.1707, allocations: 437 kB / 0.6709 GB, free: 6.707 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000209/0.1709, allocations: 116 kB / 0.671 GB, free: 6.594 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002781/0.1737, allocations: 1.456 MB / 0.6725 GB, free: 5.113 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.359e-05/0.1738, allocations: 0 / 0.6725 GB, free: 5.113 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001191/0.1739, allocations: 40 kB / 0.6725 GB, free: 5.074 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.048e-05/0.1739, allocations: 24 kB / 0.6725 GB, free: 5.051 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002847/0.1768, allocations: 1.343 MB / 0.6738 GB, free: 3.707 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.008327/0.1851, allocations: 5.174 MB / 0.6789 GB, free: 14.3 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.008138/0.1933, allocations: 2.911 MB / 0.6817 GB, free: 11.25 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00189/0.1952, allocations: 0.9152 MB / 0.6826 GB, free: 10.33 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005721/0.1958, allocations: 0.7255 MB / 0.6833 GB, free: 9.438 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002127/0.1979, allocations: 1.067 MB / 0.6844 GB, free: 8.348 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=129): time 4.198e-06/0.1979, allocations: 0 / 0.6844 GB, free: 8.348 MB / 494.1 MB
Notification: Performance of matching and sorting (n=129): time 0.04365/0.2416, allocations: 10.54 MB / 0.6947 GB, free: 13.77 MB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.433e-05/0.2416, allocations: 124.1 kB / 0.6948 GB, free: 13.62 MB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00216/0.2438, allocations: 1.414 MB / 0.6962 GB, free: 12.2 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002622/0.2441, allocations: 49.7 kB / 0.6962 GB, free: 12.14 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004728/0.2446, allocations: 0.6362 MB / 0.6968 GB, free: 11.5 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0005677/0.2451, allocations: 491.8 kB / 0.6973 GB, free: 11.02 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007528/0.2459, allocations: 367.4 kB / 0.6977 GB, free: 10.66 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 8.321e-05/0.246, allocations: 305.1 kB / 0.698 GB, free: 10.36 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002225/0.2482, allocations: 0.9917 MB / 0.6989 GB, free: 9.363 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002363/0.2506, allocations: 1.219 MB / 0.7001 GB, free: 8.094 MB / 0.4981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004396/0.255, allocations: 2.126 MB / 0.7022 GB, free: 5.918 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.821e-06/0.255, allocations: 4 kB / 0.7022 GB, free: 5.914 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=252) (initialization): time 0.009259/0.2643, allocations: 3.727 MB / 0.7058 GB, free: 2.176 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 2.14e-05/0.2643, allocations: 8.062 kB / 0.7058 GB, free: 2.168 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.112e-05/0.2644, allocations: 8 kB / 0.7058 GB, free: 2.16 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009295/0.2737, allocations: 2.675 MB / 0.7085 GB, free: 15.47 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001548/0.2752, allocations: 331.5 kB / 0.7088 GB, free: 15.15 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006267/0.2815, allocations: 2.691 MB / 0.7114 GB, free: 12.43 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0022/0.2837, allocations: 254.7 kB / 0.7116 GB, free: 12.18 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002475/0.284, allocations: 67.97 kB / 0.7117 GB, free: 12.11 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,iEEET1.rotatingExciter.sISO.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (195):
 * Single equations (assignments): 193
 * 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.0007102/0.2847, allocations: 310.9 kB / 0.712 GB, free: 11.8 MB / 0.5137 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004188/0.2852, allocations: 170.9 kB / 0.7122 GB, free: 11.63 MB / 0.5137 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01615/0.3013, allocations: 4.859 MB / 0.7169 GB, free: 6.75 MB / 0.5137 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.758e-05/0.3014, allocations: 7.922 kB / 0.7169 GB, free: 6.746 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.218e-06/0.3014, allocations: 0 / 0.7169 GB, free: 6.746 MB / 0.5137 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005078/0.3019, allocations: 287.6 kB / 0.7172 GB, free: 6.445 MB / 0.5137 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003678/0.3023, allocations: 31.98 kB / 0.7172 GB, free: 6.414 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001566/0.3038, allocations: 119.9 kB / 0.7173 GB, free: 6.297 MB / 0.5137 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00145/0.3053, allocations: 0.5512 MB / 0.7179 GB, free: 5.742 MB / 0.5137 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.007943/0.3133, allocations: 2.796 MB / 0.7206 GB, free: 2.941 MB / 0.5137 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.00586/0.3191, allocations: 2.959 MB / 0.7235 GB, free: 15.96 MB / 0.5294 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001745/0.3209, allocations: 0.7559 MB / 0.7242 GB, free: 15.21 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.001495/0.3224, allocations: 0.936 MB / 0.7252 GB, free: 14.27 MB / 0.5294 GB
Notification: Performance of Backend: time 5.81e-07/0.3224, allocations: 0 / 0.7252 GB, free: 14.27 MB / 0.5294 GB
Notification: Performance of simCode: created initialization part: time 0.008256/0.3307, allocations: 2.348 MB / 0.7274 GB, free: 11.91 MB / 0.5294 GB
Notification: Performance of SimCode: time 0.005007/0.3357, allocations: 3.763 MB / 0.7311 GB, free: 8.09 MB / 0.5294 GB
Notification: Performance of Templates: time 0.04295/0.3787, allocations: 27.69 MB / 0.7582 GB, free: 12.39 MB / 0.5606 GB
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.IEEET1.makefile
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.IEEET1.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.IEEET1.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.IEEET1.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.IEEET1.sim & ./OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.IEEET1  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.Controls.PSSE.ES.IEEET1.pipe 2>&1)