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.001257/0.001257, allocations: 107.7 kB / 16.41 MB, free: 6.512 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.398/1.398, allocations: 222.9 MB / 240.1 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.001971/0.001972, allocations: 188.5 kB / 290.4 MB, free: 2.27 MB / 238.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5401/0.5401, allocations: 91.91 MB / 432.4 MB, free: 9.172 MB / 302.1 MB Notification: Performance of FrontEnd - loaded program: time 0.001498/0.001498, allocations: 78.56 kB / 0.5734 GB, free: 33.79 MB / 430.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1618/0.1633, allocations: 71.29 MB / 0.643 GB, free: 3.566 MB / 446.1 MB Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSSE.ES.ESST4B): time 0.01005/0.1734, allocations: 8.93 MB / 0.6517 GB, free: 10.59 MB / 462.1 MB Notification: Performance of NFInst.instExpressions: time 0.007068/0.1806, allocations: 2.904 MB / 0.6546 GB, free: 7.684 MB / 462.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.002224/0.1828, allocations: 19.94 kB / 0.6546 GB, free: 7.664 MB / 462.1 MB Notification: Performance of NFTyping.typeComponents: time 0.00111/0.184, allocations: 329 kB / 0.6549 GB, free: 7.34 MB / 462.1 MB Notification: Performance of NFTyping.typeBindings: time 0.003731/0.1877, allocations: 1.149 MB / 0.656 GB, free: 6.188 MB / 462.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.002388/0.1901, allocations: 1.001 MB / 0.657 GB, free: 5.199 MB / 462.1 MB Notification: Performance of NFFlatten.flatten: time 0.003931/0.1941, allocations: 2.456 MB / 0.6594 GB, free: 2.734 MB / 462.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.0009007/0.195, allocations: 0.5347 MB / 0.6599 GB, free: 2.168 MB / 462.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.001665/0.1967, allocations: 0.9962 MB / 0.6609 GB, free: 1.168 MB / 462.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.001521/0.1982, allocations: 1.225 MB / 0.6621 GB, free: 15.94 MB / 478.1 MB Notification: Performance of NFPackage.collectConstants: time 0.0003538/0.1986, allocations: 212 kB / 0.6623 GB, free: 15.73 MB / 478.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0008783/0.1995, allocations: 439.7 kB / 0.6627 GB, free: 15.3 MB / 478.1 MB Notification: Performance of NFScalarize.scalarize: time 0.0004398/0.1999, allocations: 0.5137 MB / 0.6632 GB, free: 14.79 MB / 478.1 MB Notification: Performance of NFVerifyModel.verify: time 0.0009923/0.2009, allocations: 1.022 MB / 0.6642 GB, free: 13.76 MB / 478.1 MB Notification: Performance of NFConvertDAE.convert: time 0.003312/0.2042, allocations: 2.597 MB / 0.6668 GB, free: 11.15 MB / 478.1 MB Notification: Performance of FrontEnd - DAE generated: time 8.415e-06/0.2043, allocations: 0 / 0.6668 GB, free: 11.15 MB / 478.1 MB Notification: Performance of FrontEnd: time 2.715e-06/0.2043, allocations: 7.938 kB / 0.6668 GB, free: 11.14 MB / 478.1 MB Notification: Performance of Transformations before backend: time 3.216e-05/0.2043, allocations: 0 / 0.6668 GB, free: 11.14 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.01409/0.2184, allocations: 3.849 MB / 0.6705 GB, free: 7.234 MB / 478.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.819e-05/0.2185, allocations: 15.97 kB / 0.6705 GB, free: 7.219 MB / 478.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005567/0.2241, allocations: 0.9225 MB / 0.6714 GB, free: 6.293 MB / 478.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.002532/0.2266, allocations: 1.207 MB / 0.6726 GB, free: 5.035 MB / 478.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006125/0.2272, allocations: 0.5007 MB / 0.6731 GB, free: 4.453 MB / 478.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002701/0.2275, allocations: 127.9 kB / 0.6732 GB, free: 4.328 MB / 478.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.003545/0.2311, allocations: 1.699 MB / 0.6749 GB, free: 2.566 MB / 478.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 2.867e-05/0.2311, allocations: 3.938 kB / 0.6749 GB, free: 2.562 MB / 478.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001508/0.2313, allocations: 52 kB / 0.6749 GB, free: 2.512 MB / 478.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.626e-05/0.2313, allocations: 28 kB / 0.675 GB, free: 2.484 MB / 478.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003512/0.2348, allocations: 1.551 MB / 0.6765 GB, free: 0.9258 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.01146/0.2463, allocations: 5.889 MB / 0.6822 GB, free: 10.77 MB / 494.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.009836/0.2562, allocations: 3.403 MB / 0.6856 GB, free: 7.227 MB / 494.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.00255/0.2588, allocations: 1.026 MB / 0.6866 GB, free: 6.195 MB / 494.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.0001568/0.259, allocations: 21.28 kB / 0.6866 GB, free: 6.172 MB / 494.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.389e-05/0.259, allocations: 51.88 kB / 0.6866 GB, free: 6.113 MB / 494.1 MB Notification: Performance of pre-optimization done (n=136): time 4.158e-06/0.259, allocations: 0 / 0.6866 GB, free: 6.113 MB / 494.1 MB Notification: Performance of matching and sorting (n=136): time 0.04279/0.3018, allocations: 10.56 MB / 0.6969 GB, free: 11.49 MB / 0.4981 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001514/0.302, allocations: 193.2 kB / 0.6971 GB, free: 11.25 MB / 0.4981 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002393/0.3044, allocations: 1.46 MB / 0.6986 GB, free: 9.785 MB / 0.4981 GB Notification: Performance of collectPreVariables (initialization): time 0.0004978/0.3049, allocations: 49.7 kB / 0.6986 GB, free: 9.73 MB / 0.4981 GB Notification: Performance of collectInitialEqns (initialization): time 0.000569/0.3055, allocations: 0.6824 MB / 0.6993 GB, free: 9.039 MB / 0.4981 GB Notification: Performance of collectInitialBindings (initialization): time 0.0006297/0.3061, allocations: 497 kB / 0.6997 GB, free: 8.547 MB / 0.4981 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.000703/0.3069, allocations: 375.4 kB / 0.7001 GB, free: 8.172 MB / 0.4981 GB Notification: Performance of setup shared object (initialization): time 0.000126/0.307, allocations: 313.1 kB / 0.7004 GB, free: 7.863 MB / 0.4981 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.002657/0.3097, allocations: 1.114 MB / 0.7015 GB, free: 6.738 MB / 0.4981 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.002873/0.3125, allocations: 1.368 MB / 0.7028 GB, free: 5.289 MB / 0.4981 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.005103/0.3177, allocations: 2.346 MB / 0.7051 GB, free: 2.887 MB / 0.4981 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.399e-05/0.3177, allocations: 4 kB / 0.7051 GB, free: 2.883 MB / 0.4981 GB Notification: Performance of matching and sorting (n=261) (initialization): time 0.01186/0.3296, allocations: 4.351 MB / 0.7094 GB, free: 14.52 MB / 0.5137 GB Notification: Performance of prepare postOptimizeDAE: time 3.886e-05/0.3297, allocations: 7.938 kB / 0.7094 GB, free: 14.51 MB / 0.5137 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.879e-05/0.3297, allocations: 4.438 kB / 0.7094 GB, free: 14.51 MB / 0.5137 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.01315/0.3429, allocations: 3.582 MB / 0.7129 GB, free: 10.92 MB / 0.5137 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00153/0.3444, allocations: 295.5 kB / 0.7132 GB, free: 10.63 MB / 0.5137 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008454/0.3529, allocations: 3.447 MB / 0.7165 GB, free: 7.152 MB / 0.5137 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002772/0.3557, allocations: 286.1 kB / 0.7168 GB, free: 6.871 MB / 0.5137 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002321/0.356, allocations: 71.97 kB / 0.7169 GB, free: 6.801 MB / 0.5137 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003002/0.359, allocations: 1.3 MB / 0.7181 GB, free: 5.492 MB / 0.5137 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002602/0.3616, allocations: 1.341 MB / 0.7194 GB, free: 4.07 MB / 0.5137 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.004815/0.3664, allocations: 2.288 MB / 0.7217 GB, free: 1.727 MB / 0.5137 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 5.21e-06/0.3665, allocations: 4 kB / 0.7217 GB, free: 1.723 MB / 0.5137 GB Notification: Performance of matching and sorting (n=261) (initialization_lambda0): time 0.0111/0.3776, allocations: 4.238 MB / 0.7258 GB, free: 13.47 MB / 0.5294 GB Notification: Performance of prepare postOptimizeDAE: time 3.485e-05/0.3777, allocations: 11.98 kB / 0.7258 GB, free: 13.46 MB / 0.5294 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.368e-05/0.3777, allocations: 4 kB / 0.7258 GB, free: 13.46 MB / 0.5294 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.01265/0.3903, allocations: 3.535 MB / 0.7293 GB, free: 9.914 MB / 0.5294 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001637/0.392, allocations: 299.6 kB / 0.7296 GB, free: 9.621 MB / 0.5294 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008156/0.4002, allocations: 3.33 MB / 0.7328 GB, free: 6.262 MB / 0.5294 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002855/0.4031, allocations: 254.5 kB / 0.7331 GB, free: 6.012 MB / 0.5294 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002092/0.4033, allocations: 67.98 kB / 0.7331 GB, free: 5.945 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.0005755/0.4039, allocations: 276.9 kB / 0.7334 GB, free: 5.668 MB / 0.5294 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000628/0.4045, allocations: 169.8 kB / 0.7336 GB, free: 5.5 MB / 0.5294 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01668/0.4212, allocations: 5.122 MB / 0.7386 GB, free: 360 kB / 0.5294 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.416e-05/0.4213, allocations: 20 kB / 0.7386 GB, free: 340 kB / 0.5294 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.071e-05/0.4213, allocations: 0 / 0.7386 GB, free: 340 kB / 0.5294 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.522e-05/0.4213, allocations: 7.969 kB / 0.7386 GB, free: 332 kB / 0.5294 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.02049/0.4418, allocations: 7.416 MB / 0.7458 GB, free: 8.766 MB / 0.545 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.824e-05/0.4419, allocations: 0 / 0.7458 GB, free: 8.766 MB / 0.545 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001217/0.4431, allocations: 179.8 kB / 0.746 GB, free: 8.59 MB / 0.545 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.007469/0.4506, allocations: 2.232 MB / 0.7482 GB, free: 6.352 MB / 0.545 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0003419/0.451, allocations: 28 kB / 0.7482 GB, free: 6.324 MB / 0.545 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02717/0.4782, allocations: 11.9 MB / 0.7598 GB, free: 10.14 MB / 0.5606 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.364e-06/0.4782, allocations: 0 / 0.7598 GB, free: 10.14 MB / 0.5606 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02043/0.4987, allocations: 6.473 MB / 0.7662 GB, free: 3.621 MB / 0.5606 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0008513/0.4996, allocations: 278.5 kB / 0.7664 GB, free: 3.328 MB / 0.5606 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000371/0.4999, allocations: 28 kB / 0.7664 GB, free: 3.301 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001783/0.5017, allocations: 151.8 kB / 0.7666 GB, free: 3.152 MB / 0.5606 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001125/0.5029, allocations: 289.3 kB / 0.7669 GB, free: 2.871 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001108/0.503, allocations: 35.98 kB / 0.7669 GB, free: 2.836 MB / 0.5606 GB Notification: Performance of sorting global known variables: time 0.001559/0.5046, allocations: 0.9652 MB / 0.7678 GB, free: 1.875 MB / 0.5606 GB Notification: Performance of sort global known variables: time 3.71e-07/0.5046, allocations: 0 / 0.7678 GB, free: 1.875 MB / 0.5606 GB Notification: Performance of remove unused functions: time 0.002749/0.5073, allocations: 0.9439 MB / 0.7688 GB, free: 0.9297 MB / 0.5606 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 3 * Number of states: 13 (gENCLS.delta,gENCLS.eq,gENROU.w,gENROU.delta,gENROU.Epd,gENROU.Epq,gENROU.PSIkd,gENROU.PSIkq,eSST4B.VA.state,eSST4B.VR1.y,eSST4B.VM1.y,eSST4B.TransducerDelay.state,gENCLS.omega) * Number of discrete variables: 0 () * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (87): * Single equations (assignments): 85 * 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 {(1,6), (26,23)} Notification: Performance of Backend phase and start with SimCode phase: time 0.0003864/0.5077, allocations: 159.1 kB / 0.7689 GB, free: 0.7656 MB / 0.5606 GB Notification: Performance of simCode: created initialization part: time 0.3312/0.839, allocations: 5.393 MB / 0.7742 GB, free: 119.2 MB / 0.5606 GB Notification: Performance of simCode: created event and clocks part: time 8.556e-06/0.839, allocations: 7.062 kB / 0.7742 GB, free: 119.2 MB / 0.5606 GB Notification: Performance of simCode: created simulation system equations: time 0.007019/0.8461, allocations: 2.176 MB / 0.7763 GB, free: 118.9 MB / 0.5606 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001621/0.8477, allocations: 159.2 kB / 0.7765 GB, free: 118.9 MB / 0.5606 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00804/0.8558, allocations: 3.548 MB / 0.7799 GB, free: 117.5 MB / 0.5606 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009956/0.8568, allocations: 1.284 MB / 0.7812 GB, free: 116.8 MB / 0.5606 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0006611/0.8575, allocations: 108.2 kB / 0.7813 GB, free: 116.8 MB / 0.5606 GB Notification: Performance of SimCode: time 1.163e-06/0.8575, allocations: 0.5938 kB / 0.7813 GB, free: 116.8 MB / 0.5606 GB Notification: Performance of Templates: time 0.03646/0.8939, allocations: 31.88 MB / 0.8124 GB, free: 93.01 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)