Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT.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.Examples.TwoAreas.Two_Areas_PSAT,tolerance=1e-06,outputFormat="empty",numberOfIntervals=200000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT") translateModel(OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT,tolerance=1e-06,outputFormat="empty",numberOfIntervals=200000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001321/0.001321, allocations: 108 kB / 15.61 MB, free: 152 kB / 14.72 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.362/1.362, allocations: 222.9 MB / 239.2 MB, free: 4.613 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.001489/0.001489, allocations: 195.6 kB / 289.5 MB, free: 2.332 MB / 238.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5079/0.5079, allocations: 91.9 MB / 431.6 MB, free: 9.32 MB / 302.1 MB Notification: Performance of FrontEnd - loaded program: time 0.001608/0.001609, allocations: 75.88 kB / 0.5019 GB, free: 7.797 MB / 382.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.38/0.3817, allocations: 71.26 MB / 0.5715 GB, free: 36.57 MB / 430.1 MB Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT): time 0.01097/0.3927, allocations: 14.06 MB / 0.5852 GB, free: 27.17 MB / 430.1 MB Notification: Performance of NFInst.instExpressions: time 0.008222/0.401, allocations: 4.123 MB / 0.5892 GB, free: 24.19 MB / 430.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.002708/0.4037, allocations: 21.88 kB / 0.5892 GB, free: 24.19 MB / 430.1 MB Notification: Performance of NFTyping.typeComponents: time 0.002146/0.4059, allocations: 0.6672 MB / 0.5899 GB, free: 23.84 MB / 430.1 MB Notification: Performance of NFTyping.typeBindings: time 0.005033/0.411, allocations: 1.601 MB / 0.5914 GB, free: 22.75 MB / 430.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.003123/0.4141, allocations: 1.269 MB / 0.5927 GB, free: 22.1 MB / 430.1 MB Notification: Performance of NFFlatten.flatten: time 0.006704/0.4208, allocations: 4.052 MB / 0.5966 GB, free: 20.02 MB / 430.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.001786/0.4227, allocations: 0.7668 MB / 0.5974 GB, free: 19.63 MB / 430.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.003186/0.4259, allocations: 1.63 MB / 0.599 GB, free: 18.79 MB / 430.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.004214/0.4301, allocations: 2.132 MB / 0.6011 GB, free: 17.74 MB / 430.1 MB Notification: Performance of NFPackage.collectConstants: time 0.002065/0.4322, allocations: 368.5 kB / 0.6014 GB, free: 17.74 MB / 430.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.002739/0.4349, allocations: 0.5131 MB / 0.6019 GB, free: 17.7 MB / 430.1 MB Notification: Performance of NFScalarize.scalarize: time 0.001892/0.4369, allocations: 0.848 MB / 0.6027 GB, free: 17.11 MB / 430.1 MB Notification: Performance of NFVerifyModel.verify: time 0.004086/0.441, allocations: 1.768 MB / 0.6045 GB, free: 15.9 MB / 430.1 MB Notification: Performance of NFConvertDAE.convert: time 0.008195/0.4492, allocations: 4.528 MB / 0.6089 GB, free: 13.95 MB / 430.1 MB Notification: Performance of FrontEnd - DAE generated: time 9.518e-06/0.4492, allocations: 4.969 kB / 0.6089 GB, free: 13.94 MB / 430.1 MB Notification: Performance of FrontEnd: time 2.846e-06/0.4492, allocations: 0 / 0.6089 GB, free: 13.94 MB / 430.1 MB Notification: Performance of Transformations before backend: time 0.0001974/0.4494, allocations: 0 / 0.6089 GB, free: 13.94 MB / 430.1 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 451 * Number of variables: 451 Notification: Performance of Generate backend data structure: time 0.02699/0.4764, allocations: 5.787 MB / 0.6145 GB, free: 11.48 MB / 430.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.557e-05/0.4765, allocations: 10.16 kB / 0.6146 GB, free: 11.48 MB / 430.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01536/0.4919, allocations: 2.04 MB / 0.6165 GB, free: 11.07 MB / 430.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.007009/0.4989, allocations: 2.027 MB / 0.6185 GB, free: 9.781 MB / 430.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007659/0.4998, allocations: 441 kB / 0.6189 GB, free: 9.426 MB / 430.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.001631/0.5014, allocations: 199.1 kB / 0.6191 GB, free: 9.355 MB / 430.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.009853/0.5113, allocations: 2.312 MB / 0.6214 GB, free: 8.48 MB / 430.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 8.868e-05/0.5114, allocations: 1.094 kB / 0.6214 GB, free: 8.48 MB / 430.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000834/0.5123, allocations: 73.72 kB / 0.6215 GB, free: 8.422 MB / 430.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.424e-05/0.5124, allocations: 47 kB / 0.6215 GB, free: 8.418 MB / 430.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006766/0.5191, allocations: 2.322 MB / 0.6238 GB, free: 7.469 MB / 430.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.02243/0.5416, allocations: 9.71 MB / 0.6333 GB, free: 14.79 MB / 446.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.01025/0.5519, allocations: 4.18 MB / 0.6373 GB, free: 10.38 MB / 446.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.003633/0.5555, allocations: 1.607 MB / 0.6389 GB, free: 8.77 MB / 446.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.0003802/0.5559, allocations: 37.97 kB / 0.6389 GB, free: 8.734 MB / 446.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.565e-05/0.556, allocations: 64.5 kB / 0.639 GB, free: 8.66 MB / 446.1 MB Notification: Performance of pre-optimization done (n=223): time 5.721e-06/0.556, allocations: 0 / 0.639 GB, free: 8.66 MB / 446.1 MB Notification: Performance of matching and sorting (n=223): time 0.09164/0.6477, allocations: 20.08 MB / 0.6586 GB, free: 4.516 MB / 462.1 MB Notification: Performance of inlineWhenForInitialization (initialization): time 5.818e-05/0.6478, allocations: 73.41 kB / 0.6587 GB, free: 4.434 MB / 462.1 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005604/0.6534, allocations: 2.691 MB / 0.6613 GB, free: 1.73 MB / 462.1 MB Notification: Performance of collectPreVariables (initialization): time 0.0008931/0.6543, allocations: 73.7 kB / 0.6614 GB, free: 1.652 MB / 462.1 MB Notification: Performance of collectInitialEqns (initialization): time 0.001172/0.6555, allocations: 1.169 MB / 0.6625 GB, free: 484 kB / 462.1 MB Notification: Performance of collectInitialBindings (initialization): time 0.001619/0.6571, allocations: 0.805 MB / 0.6633 GB, free: 15.66 MB / 478.1 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.002729/0.6598, allocations: 0.5894 MB / 0.6639 GB, free: 15.06 MB / 478.1 MB Notification: Performance of setup shared object (initialization): time 9.482e-05/0.66, allocations: 301.1 kB / 0.6642 GB, free: 14.76 MB / 478.1 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.004242/0.6642, allocations: 1.795 MB / 0.6659 GB, free: 12.96 MB / 478.1 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.004412/0.6686, allocations: 2.154 MB / 0.668 GB, free: 10.73 MB / 478.1 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.062/0.7307, allocations: 13.11 MB / 0.6808 GB, free: 13.49 MB / 494.1 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.505e-05/0.7307, allocations: 4 kB / 0.6808 GB, free: 13.48 MB / 494.1 MB Notification: Performance of matching and sorting (n=429) (initialization): time 0.02816/0.7589, allocations: 7.452 MB / 0.6881 GB, free: 5.957 MB / 494.1 MB Notification: Performance of prepare postOptimizeDAE: time 5.207e-05/0.759, allocations: 8 kB / 0.6881 GB, free: 5.949 MB / 494.1 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.688e-05/0.759, allocations: 4 kB / 0.6881 GB, free: 5.945 MB / 494.1 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.01414/0.7732, allocations: 3.231 MB / 0.6913 GB, free: 2.668 MB / 494.1 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008777/0.782, allocations: 1.428 MB / 0.6927 GB, free: 1.238 MB / 494.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03077/0.8128, allocations: 16.89 MB / 0.7092 GB, free: 15.63 MB / 0.5137 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00605/0.8189, allocations: 393.1 kB / 0.7096 GB, free: 15.25 MB / 0.5137 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00108/0.82, allocations: 116 kB / 0.7097 GB, free: 15.13 MB / 0.5137 GB Warning: Assuming fixed start value for the following 8 variables: g4.order6_1.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real g3.order6_1.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real g2.order6_1.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real g1.order6_1.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real g1.order6_1.delta:VARIABLE(flow=false start = g1.order6_1.delta0 unit = "rad" fixed = true ) "Rotor angle" type: Real g1.order6_1.e1d:VARIABLE(start = g1.order6_1.e1d0 unit = "1" fixed = true ) "d-axis transient voltage" type: Real g2.order6_1.delta:VARIABLE(flow=false start = g2.order6_1.delta0 unit = "rad" fixed = true ) "Rotor angle" type: Real g2.order6_1.e1d:VARIABLE(start = g2.order6_1.e1d0 unit = "1" fixed = true ) "d-axis transient voltage" type: Real Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 27 * 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 (361): * Single equations (assignments): 347 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 9 * Torn equation systems: 5 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 9 systems {(2,100.0%), (2,100.0%), (2,100.0%), (2,100.0%), (2,100.0%), (2,100.0%), (2,100.0%), (2,100.0%), (2,100.0%)} * Non-linear Jacobian (size): 0 systems * Without analytic Jacobian (size): 0 systems Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 4 systems {(4,2,75.0%), (4,2,75.0%), (1,1,100.0%), (3,17,88.9%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(8,22)} Notification: Performance of prepare postOptimizeDAE: time 0.002713/0.8228, allocations: 0.4939 MB / 0.7101 GB, free: 14.63 MB / 0.5137 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001699/0.8245, allocations: 312.1 kB / 0.7104 GB, free: 14.32 MB / 0.5137 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02703/0.8515, allocations: 8.114 MB / 0.7184 GB, free: 6.184 MB / 0.5137 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.641e-05/0.8516, allocations: 28 kB / 0.7184 GB, free: 6.156 MB / 0.5137 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 5.21e-06/0.8516, allocations: 0 / 0.7184 GB, free: 6.156 MB / 0.5137 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.67e-05/0.8517, allocations: 11.95 kB / 0.7184 GB, free: 6.145 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.03494/0.8867, allocations: 12.14 MB / 0.7303 GB, free: 9.785 MB / 0.5294 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.468e-05/0.8867, allocations: 0 / 0.7303 GB, free: 9.785 MB / 0.5294 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001944/0.8887, allocations: 227.7 kB / 0.7305 GB, free: 9.562 MB / 0.5294 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.01452/0.9032, allocations: 4.184 MB / 0.7346 GB, free: 5.371 MB / 0.5294 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0008704/0.9041, allocations: 51.92 kB / 0.7346 GB, free: 5.32 MB / 0.5294 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04125/0.9454, allocations: 16.58 MB / 0.7508 GB, free: 4.574 MB / 0.545 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.406e-06/0.9454, allocations: 0 / 0.7508 GB, free: 4.574 MB / 0.545 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03123/0.9767, allocations: 10.16 MB / 0.7607 GB, free: 10.39 MB / 0.5606 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001511/0.9782, allocations: 460.6 kB / 0.7612 GB, free: 9.914 MB / 0.5606 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0009855/0.9792, allocations: 44 kB / 0.7612 GB, free: 9.871 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00284/0.9821, allocations: 167.8 kB / 0.7614 GB, free: 9.707 MB / 0.5606 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001277/0.9834, allocations: 382.4 kB / 0.7617 GB, free: 9.328 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001767/0.9835, allocations: 56 kB / 0.7618 GB, free: 9.273 MB / 0.5606 GB Notification: Performance of sorting global known variables: time 0.002532/0.9861, allocations: 1.779 MB / 0.7635 GB, free: 7.496 MB / 0.5606 GB Notification: Performance of sort global known variables: time 1.6e-07/0.9861, allocations: 0 / 0.7635 GB, free: 7.496 MB / 0.5606 GB Notification: Performance of remove unused functions: time 0.003606/0.9897, allocations: 1.097 MB / 0.7646 GB, free: 6.398 MB / 0.5606 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 1 * Number of states: 24 (g1.order6_1.delta,g1.order6_1.w,g1.order6_1.e1q,g1.order6_1.e1d,g1.order6_1.e2q,g1.order6_1.e2d,g2.order6_1.delta,g2.order6_1.w,g2.order6_1.e1q,g2.order6_1.e1d,g2.order6_1.e2q,g2.order6_1.e2d,g3.order6_1.delta,g3.order6_1.w,g3.order6_1.e1q,g3.order6_1.e1d,g3.order6_1.e2q,g3.order6_1.e2d,g4.order6_1.delta,g4.order6_1.w,g4.order6_1.e1q,g4.order6_1.e1d,g4.order6_1.e2q,g4.order6_1.e2d) * 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 (136): * Single equations (assignments): 135 * 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 {(54,42)} Notification: Performance of Backend phase and start with SimCode phase: time 0.0004177/0.9901, allocations: 171.3 kB / 0.7648 GB, free: 6.227 MB / 0.5606 GB Notification: Performance of simCode: created initialization part: time 0.2714/1.262, allocations: 6.407 MB / 0.771 GB, free: 165.9 MB / 0.5606 GB Notification: Performance of simCode: created event and clocks part: time 7.323e-06/1.262, allocations: 0.5312 kB / 0.771 GB, free: 165.9 MB / 0.5606 GB Notification: Performance of simCode: created simulation system equations: time 0.01282/1.274, allocations: 3.394 MB / 0.7743 GB, free: 165.6 MB / 0.5606 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003363/1.278, allocations: 226.8 kB / 0.7745 GB, free: 165.5 MB / 0.5606 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.008788/1.287, allocations: 5.751 MB / 0.7802 GB, free: 163.2 MB / 0.5606 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001054/1.288, allocations: 1.76 MB / 0.7819 GB, free: 162.4 MB / 0.5606 GB Notification: Performance of simCode: alias equations: time 0.001444/1.289, allocations: 257.7 kB / 0.7821 GB, free: 162.3 MB / 0.5606 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0006926/1.29, allocations: 166 kB / 0.7823 GB, free: 162.3 MB / 0.5606 GB Notification: Performance of SimCode: time 7.51e-07/1.29, allocations: 0 / 0.7823 GB, free: 162.3 MB / 0.5606 GB Notification: Performance of Templates: time 0.09138/1.381, allocations: 56.11 MB / 0.8371 GB, free: 131 MB / 0.5606 GB make -j1 -f OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT.makefile (rm -f OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT.sim & ./OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Examples.TwoAreas.Two_Areas_PSAT.pipe 2>&1)