Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.conf.json 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/Modelica 4.0.0+maint.om/package.mo", uses=false) 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/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 ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 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 Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo) Running command: translateModel(OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS,tolerance=1e-06,outputFormat="empty",numberOfIntervals=200000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS") translateModel(OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS,tolerance=1e-06,outputFormat="empty",numberOfIntervals=200000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.005006/0.005006, allocations: 171.8 kB / 15.12 MB, free: 5.941 MB / 13.93 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.4/1.4, allocations: 223.2 MB / 239 MB, free: 36 kB / 186.7 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0009296/0.0009298, allocations: 95.16 kB / 290.2 MB, free: 4.535 MB / 234.7 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4786/0.4786, allocations: 78.58 MB / 419.9 MB, free: 36.44 MB / 346.7 MB Notification: Performance of FrontEnd - loaded program: time 0.0003726/0.0003726, allocations: 8 kB / 499.8 MB, free: 7.281 MB / 362.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1445/0.1449, allocations: 69.37 MB / 0.5558 GB, free: 1.852 MB / 426.7 MB Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS): time 0.005741/0.1507, allocations: 5.149 MB / 0.5608 GB, free: 12.68 MB / 442.7 MB Notification: Performance of NFInst.instExpressions: time 0.003678/0.1544, allocations: 2.08 MB / 0.5628 GB, free: 10.6 MB / 442.7 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.0004024/0.1548, allocations: 11.94 kB / 0.5629 GB, free: 10.59 MB / 442.7 MB Notification: Performance of NFTyping.typeComponents: time 0.0005466/0.1554, allocations: 166.8 kB / 0.563 GB, free: 10.42 MB / 442.7 MB [/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation transformer.m <> 1.0, <> on Real numbers is only allowed inside functions. Notification: Performance of NFTyping.typeBindings: time 0.001468/0.1569, allocations: 0.6547 MB / 0.5637 GB, free: 9.77 MB / 442.7 MB Notification: Performance of NFTyping.typeClassSections: time 0.001141/0.158, allocations: 0.5023 MB / 0.5641 GB, free: 9.27 MB / 442.7 MB Notification: Performance of NFFlatten.flatten: time 0.001313/0.1593, allocations: 1.006 MB / 0.5651 GB, free: 8.262 MB / 442.7 MB Notification: Performance of NFFlatten.resolveConnections: time 0.0003372/0.1597, allocations: 243.7 kB / 0.5654 GB, free: 8.008 MB / 442.7 MB Notification: Performance of NFEvalConstants.evaluate: time 0.0006319/0.1603, allocations: 0.5683 MB / 0.5659 GB, free: 7.438 MB / 442.7 MB Notification: Performance of NFSimplifyModel.simplify: time 0.0007278/0.161, allocations: 0.6575 MB / 0.5666 GB, free: 6.777 MB / 442.7 MB Notification: Performance of NFPackage.collectConstants: time 0.0001119/0.1612, allocations: 60 kB / 0.5666 GB, free: 6.719 MB / 442.7 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0003573/0.1615, allocations: 171.8 kB / 0.5668 GB, free: 6.551 MB / 442.7 MB Notification: Performance of NFScalarize.scalarize: time 0.0002102/0.1618, allocations: 251 kB / 0.567 GB, free: 6.305 MB / 442.7 MB Notification: Performance of NFVerifyModel.verify: time 0.0001725/0.1619, allocations: 225.1 kB / 0.5672 GB, free: 6.078 MB / 442.7 MB Notification: Performance of NFConvertDAE.convert: time 0.001207/0.1631, allocations: 1.445 MB / 0.5686 GB, free: 4.629 MB / 442.7 MB Notification: Performance of FrontEnd - DAE generated: time 2.885e-06/0.1632, allocations: 0 / 0.5686 GB, free: 4.629 MB / 442.7 MB Notification: Performance of FrontEnd: time 1.423e-06/0.1632, allocations: 0 / 0.5686 GB, free: 4.629 MB / 442.7 MB Notification: Performance of Transformations before backend: time 1.468e-05/0.1632, allocations: 0 / 0.5686 GB, free: 4.629 MB / 442.7 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 159 * Number of variables: 159 Notification: Performance of Generate backend data structure: time 0.006403/0.1696, allocations: 2.096 MB / 0.5707 GB, free: 2.48 MB / 442.7 MB Notification: Performance of prepare preOptimizeDAE: time 4.064e-05/0.1696, allocations: 8.031 kB / 0.5707 GB, free: 2.473 MB / 442.7 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00332/0.173, allocations: 0.5565 MB / 0.5712 GB, free: 1.914 MB / 442.7 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.002028/0.175, allocations: 0.9683 MB / 0.5722 GB, free: 0.8906 MB / 442.7 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002906/0.1753, allocations: 247.4 kB / 0.5724 GB, free: 0.6133 MB / 442.7 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001436/0.1755, allocations: 79.91 kB / 0.5725 GB, free: 0.5352 MB / 442.7 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.2516/0.4271, allocations: 0.9987 MB / 0.5735 GB, free: 43.97 MB / 442.7 MB Notification: Performance of preOpt findStateOrder (simulation): time 2.189e-05/0.4271, allocations: 3.562 kB / 0.5735 GB, free: 43.97 MB / 442.7 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.641e-05/0.4272, allocations: 29.5 kB / 0.5735 GB, free: 43.97 MB / 442.7 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.138e-05/0.4273, allocations: 14.78 kB / 0.5735 GB, free: 43.97 MB / 442.7 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001975/0.4292, allocations: 0.963 MB / 0.5745 GB, free: 43.96 MB / 442.7 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.005994/0.4352, allocations: 3.788 MB / 0.5782 GB, free: 43.18 MB / 442.7 MB Notification: Performance of preOpt comSubExp (simulation): time 0.01476/0.45, allocations: 4.278 MB / 0.5823 GB, free: 42.89 MB / 442.7 MB Notification: Performance of preOpt evalFunc (simulation): time 0.000169/0.4502, allocations: 14.97 kB / 0.5824 GB, free: 42.89 MB / 442.7 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.149e-05/0.4503, allocations: 50.3 kB / 0.5824 GB, free: 42.84 MB / 442.7 MB Notification: Performance of pre-optimization done (n=76): time 2.695e-06/0.4503, allocations: 1.594 kB / 0.5824 GB, free: 42.84 MB / 442.7 MB Notification: Performance of matching and sorting (n=76): time 0.01447/0.4647, allocations: 4.552 MB / 0.5868 GB, free: 42.26 MB / 442.7 MB Notification: Performance of inlineWhenForInitialization (initialization): time 4.805e-05/0.4648, allocations: 112.3 kB / 0.587 GB, free: 42.14 MB / 442.7 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002044/0.4669, allocations: 1.159 MB / 0.5881 GB, free: 41.98 MB / 442.7 MB Notification: Performance of collectPreVariables (initialization): time 0.000128/0.467, allocations: 39.64 kB / 0.5881 GB, free: 41.95 MB / 442.7 MB Notification: Performance of collectInitialEqns (initialization): time 0.0003277/0.4674, allocations: 465.6 kB / 0.5886 GB, free: 41.81 MB / 442.7 MB Notification: Performance of collectInitialBindings (initialization): time 0.0002684/0.4676, allocations: 293.2 kB / 0.5888 GB, free: 41.63 MB / 442.7 MB Notification: Performance of simplifyInitialFunctions (initialization): time 9.11e-05/0.4677, allocations: 20.97 kB / 0.5889 GB, free: 41.63 MB / 442.7 MB Notification: Performance of setup shared object (initialization): time 9.518e-05/0.4678, allocations: 348.6 kB / 0.5892 GB, free: 41.32 MB / 442.7 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.001554/0.4694, allocations: 0.6943 MB / 0.5899 GB, free: 41.31 MB / 442.7 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.001402/0.4708, allocations: 0.8134 MB / 0.5907 GB, free: 41.09 MB / 442.7 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.001375/0.4722, allocations: 0.7487 MB / 0.5914 GB, free: 40.75 MB / 442.7 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.396e-06/0.4722, allocations: 1.781 kB / 0.5914 GB, free: 40.75 MB / 442.7 MB Notification: Performance of matching and sorting (n=140) (initialization): time 0.008025/0.4802, allocations: 3.123 MB / 0.5945 GB, free: 39.8 MB / 442.7 MB Notification: Performance of prepare postOptimizeDAE: time 0.0001331/0.4804, allocations: 0.624 MB / 0.5951 GB, free: 39.14 MB / 442.7 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.889e-06/0.4804, allocations: 3.062 kB / 0.5951 GB, free: 39.14 MB / 442.7 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.004226/0.4846, allocations: 1.199 MB / 0.5962 GB, free: 38.91 MB / 442.7 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001189/0.4858, allocations: 222 kB / 0.5964 GB, free: 38.89 MB / 442.7 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.009609/0.4955, allocations: 5.312 MB / 0.6016 GB, free: 36.16 MB / 442.7 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00176/0.4972, allocations: 195.7 kB / 0.6018 GB, free: 36.1 MB / 442.7 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.809e-05/0.4973, allocations: 38.31 kB / 0.6019 GB, free: 36.09 MB / 442.7 MB Notification: Performance of prepare postOptimizeDAE: time 9.007e-06/0.4974, allocations: 6.969 kB / 0.6019 GB, free: 36.09 MB / 442.7 MB Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.007145/0.5045, allocations: 2.927 MB / 0.6047 GB, free: 35.23 MB / 442.7 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.018e-05/0.5045, allocations: 0 / 0.6047 GB, free: 35.23 MB / 442.7 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.004029/0.5086, allocations: 1.213 MB / 0.6059 GB, free: 34.99 MB / 442.7 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001029/0.5096, allocations: 209.9 kB / 0.6061 GB, free: 34.97 MB / 442.7 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00962/0.5192, allocations: 5.295 MB / 0.6113 GB, free: 32.25 MB / 442.7 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001629/0.5209, allocations: 176.8 kB / 0.6114 GB, free: 32.19 MB / 442.7 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.808e-05/0.521, allocations: 36.78 kB / 0.6115 GB, free: 32.18 MB / 442.7 MB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 7 * Number of states: 0 () * Number of discrete variables: 0 () * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (119): * Single equations (assignments): 116 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (linear and non-linear blocks): 2 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details: * Constant Jacobian: 0 * Linear Jacobian (size,density): 2 {(2,100.0%),(2,100.0%)} * Non-linear Jacobian: 0 * Without analytic Jacobian: 0 Notification: Torn system details for strict tearing set: * Linear torn systems: 1 {(7,59.2%) 13} * Non-linear torn systems: 0 Notification: Performance of prepare postOptimizeDAE: time 0.0005899/0.5216, allocations: 357.6 kB / 0.6118 GB, free: 31.88 MB / 442.7 MB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002044/0.5218, allocations: 112.8 kB / 0.6119 GB, free: 31.82 MB / 442.7 MB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.007073/0.5289, allocations: 2.641 MB / 0.6145 GB, free: 31.07 MB / 442.7 MB Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.897e-06/0.5289, allocations: 8.688 kB / 0.6145 GB, free: 31.07 MB / 442.7 MB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.083e-05/0.5289, allocations: 8.578 kB / 0.6145 GB, free: 31.06 MB / 442.7 MB Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.11e-05/0.529, allocations: 7.359 kB / 0.6145 GB, free: 31.06 MB / 442.7 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 postOpt removeSimpleEquations (simulation): time 0.008649/0.5376, allocations: 4.235 MB / 0.6187 GB, free: 29.39 MB / 442.7 MB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.227e-06/0.5376, allocations: 0 / 0.6187 GB, free: 29.39 MB / 442.7 MB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0007076/0.5383, allocations: 110.1 kB / 0.6188 GB, free: 29.38 MB / 442.7 MB Notification: Performance of postOpt tearingSystem (simulation): time 0.003281/0.5416, allocations: 1.083 MB / 0.6198 GB, free: 29.18 MB / 442.7 MB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 8.053e-05/0.5417, allocations: 17.97 kB / 0.6199 GB, free: 29.16 MB / 442.7 MB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.008205/0.5499, allocations: 4.312 MB / 0.6241 GB, free: 26.85 MB / 442.7 MB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.613e-06/0.55, allocations: 0 / 0.6241 GB, free: 26.85 MB / 442.7 MB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.008608/0.5586, allocations: 3.454 MB / 0.6274 GB, free: 25.66 MB / 442.7 MB Notification: Performance of postOpt removeConstants (simulation): time 0.0004398/0.559, allocations: 231 kB / 0.6277 GB, free: 25.46 MB / 442.7 MB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001691/0.5592, allocations: 19.48 kB / 0.6277 GB, free: 25.45 MB / 442.7 MB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001167/0.5604, allocations: 129.2 kB / 0.6278 GB, free: 25.43 MB / 442.7 MB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003585/0.5608, allocations: 136.5 kB / 0.6279 GB, free: 25.41 MB / 442.7 MB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.645e-05/0.5608, allocations: 23.34 kB / 0.628 GB, free: 25.4 MB / 442.7 MB Notification: Performance of sorting global known variables: time 0.00153/0.5624, allocations: 0.8166 MB / 0.6287 GB, free: 25.06 MB / 442.7 MB Notification: Performance of sort global known variables: time 2.61e-07/0.5624, allocations: 0 / 0.6287 GB, free: 25.06 MB / 442.7 MB Notification: Performance of remove unused functions: time 0.001629/0.564, allocations: 0.586 MB / 0.6293 GB, free: 24.97 MB / 442.7 MB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 2 * Number of states: 12 (G1.machine.delta,G1.machine.w,G1.machine.e1q,G1.machine.e1d,G1.machine.e2q,G1.machine.e2d,G1.avr.vm,G1.avr.vr,G1.avr.vf1,G1.pss.imLeadLag.TF.x_scaled[1],G1.pss.imLeadLag1.TF.x_scaled[1],G1.pss.derivativeLag.TF.x_scaled[1]) * 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 (59): * Single equations (assignments): 56 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (linear and non-linear blocks): 2 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details: * Constant Jacobian: 0 * Linear Jacobian (size,density): 2 {(2,100.0%),(2,100.0%)} * Non-linear Jacobian: 0 * Without analytic Jacobian: 0 Notification: Torn system details for strict tearing set: * Linear torn systems: 1 {(9,48.1%) 9} * Non-linear torn systems: 0 Notification: Performance of Backend phase and start with SimCode phase: time 0.0002314/0.5643, allocations: 129.6 kB / 0.6294 GB, free: 24.86 MB / 442.7 MB Notification: Performance of simCode: created initialization part: time 0.008564/0.5728, allocations: 3.876 MB / 0.6332 GB, free: 23.16 MB / 442.7 MB Notification: Performance of simCode: created event and clocks part: time 3.627e-06/0.5729, allocations: 0 / 0.6332 GB, free: 23.16 MB / 442.7 MB Notification: Performance of simCode: created simulation system equations: time 0.003015/0.5759, allocations: 1.379 MB / 0.6346 GB, free: 21.93 MB / 442.7 MB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0007603/0.5767, allocations: 103.6 kB / 0.6347 GB, free: 21.84 MB / 442.7 MB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00407/0.5807, allocations: 2.715 MB / 0.6373 GB, free: 19.41 MB / 442.7 MB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00055/0.5813, allocations: 1.115 MB / 0.6384 GB, free: 18.43 MB / 442.7 MB Notification: Performance of simCode: alias equations: time 0.0006867/0.582, allocations: 148.7 kB / 0.6386 GB, free: 18.33 MB / 442.7 MB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002688/0.5823, allocations: 68.27 kB / 0.6386 GB, free: 18.27 MB / 442.7 MB Notification: Performance of SimCode: time 5.11e-07/0.5823, allocations: 0 / 0.6386 GB, free: 18.27 MB / 442.7 MB Notification: Performance of Templates: time 0.02571/0.608, allocations: 20.66 MB / 0.6588 GB, free: 14.27 MB / 458.7 MB make -j1 -f OpenIPSL_release_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.makefile (rm -f OpenIPSL_release_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.pipe >> ../files/OpenIPSL_release_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.sim & ./OpenIPSL_release_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Examples.KundurSMIB.SMIB_AVR_PSS.pipe 2>&1)