Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.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.PSATSystems.TwoArea.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI") translateModel(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0009736/0.0009736, allocations: 92.16 kB / 16.02 MB, free: 80 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.34/1.34, allocations: 223.3 MB / 240 MB, free: 5.469 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.001198/0.001198, allocations: 176.5 kB / 291.3 MB, free: 2.141 MB / 238.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5154/0.5154, allocations: 91.93 MB / 434.3 MB, free: 10.25 MB / 302.1 MB Notification: Performance of FrontEnd - loaded program: time 0.0003738/0.000374, allocations: 15.94 kB / 0.5054 GB, free: 7.246 MB / 382.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3283/0.3287, allocations: 72.46 MB / 0.5762 GB, free: 36.79 MB / 430.1 MB Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI): time 0.01546/0.3442, allocations: 22.48 MB / 0.5981 GB, free: 20.32 MB / 430.1 MB Notification: Performance of NFInst.instExpressions: time 0.01132/0.3555, allocations: 5.175 MB / 0.6032 GB, free: 16.57 MB / 430.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.004735/0.3603, allocations: 59.19 kB / 0.6032 GB, free: 16.57 MB / 430.1 MB Notification: Performance of NFTyping.typeComponents: time 0.002962/0.3633, allocations: 0.6735 MB / 0.6039 GB, free: 16.3 MB / 430.1 MB Notification: Performance of NFTyping.typeBindings: time 0.00768/0.371, allocations: 1.731 MB / 0.6056 GB, free: 15.09 MB / 430.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.005287/0.3763, allocations: 1.936 MB / 0.6075 GB, free: 13.98 MB / 430.1 MB Notification: Performance of NFFlatten.flatten: time 0.008504/0.3848, allocations: 5.455 MB / 0.6128 GB, free: 11.23 MB / 430.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.002654/0.3875, allocations: 1.122 MB / 0.6139 GB, free: 10.66 MB / 430.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.004061/0.3916, allocations: 2.061 MB / 0.6159 GB, free: 9.672 MB / 430.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.004742/0.3963, allocations: 2.71 MB / 0.6186 GB, free: 8.383 MB / 430.1 MB Notification: Performance of NFPackage.collectConstants: time 0.001886/0.3982, allocations: 240.4 kB / 0.6188 GB, free: 8.379 MB / 430.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.002822/0.4011, allocations: 376.8 kB / 0.6191 GB, free: 8.336 MB / 430.1 MB Notification: Performance of NFScalarize.scalarize: time 0.001954/0.4031, allocations: 0.9458 MB / 0.6201 GB, free: 7.75 MB / 430.1 MB Notification: Performance of NFVerifyModel.verify: time 0.004976/0.408, allocations: 2.401 MB / 0.6224 GB, free: 6.184 MB / 430.1 MB Notification: Performance of NFConvertDAE.convert: time 0.007452/0.4155, allocations: 4.654 MB / 0.627 GB, free: 4.703 MB / 430.1 MB Notification: Performance of FrontEnd - DAE generated: time 4.92e-06/0.4155, allocations: 0 / 0.627 GB, free: 4.703 MB / 430.1 MB Notification: Performance of FrontEnd: time 2.465e-06/0.4155, allocations: 0 / 0.627 GB, free: 4.703 MB / 430.1 MB Notification: Performance of Transformations before backend: time 0.0002916/0.4158, allocations: 0 / 0.627 GB, free: 4.703 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: 891 * Number of variables: 891 Notification: Performance of Generate backend data structure: time 0.03219/0.448, allocations: 8.451 MB / 0.6352 GB, free: 0.6992 MB / 430.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.298e-05/0.4481, allocations: 12.56 kB / 0.6352 GB, free: 0.6953 MB / 430.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02824/0.4763, allocations: 4.047 MB / 0.6392 GB, free: 13.03 MB / 446.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.007737/0.4841, allocations: 2.674 MB / 0.6418 GB, free: 10.27 MB / 446.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001111/0.4853, allocations: 0.9673 MB / 0.6427 GB, free: 9.176 MB / 446.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.001179/0.4864, allocations: 344 kB / 0.6431 GB, free: 8.84 MB / 446.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.01112/0.4976, allocations: 5.013 MB / 0.648 GB, free: 3.793 MB / 446.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 7.028e-05/0.4977, allocations: 3.938 kB / 0.648 GB, free: 3.789 MB / 446.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000432/0.4981, allocations: 140 kB / 0.6481 GB, free: 3.652 MB / 446.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.48e-05/0.4982, allocations: 87.98 kB / 0.6482 GB, free: 3.566 MB / 446.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01013/0.5083, allocations: 4.533 MB / 0.6526 GB, free: 15.03 MB / 462.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.0378/0.5462, allocations: 18.91 MB / 0.6711 GB, free: 10.92 MB / 478.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.01391/0.5601, allocations: 5.737 MB / 0.6767 GB, free: 5.105 MB / 478.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.006768/0.5669, allocations: 3.066 MB / 0.6797 GB, free: 2.031 MB / 478.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.0004221/0.5673, allocations: 78.89 kB / 0.6797 GB, free: 1.953 MB / 478.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.01e-05/0.5674, allocations: 73.48 kB / 0.6798 GB, free: 1.871 MB / 478.1 MB Notification: Performance of pre-optimization done (n=381): time 5.48e-06/0.5674, allocations: 0 / 0.6798 GB, free: 1.871 MB / 478.1 MB Notification: Performance of matching and sorting (n=381): time 0.3422/0.9097, allocations: 79.8 MB / 0.7577 GB, free: 1.902 MB / 0.545 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001176/0.9098, allocations: 167.5 kB / 0.7579 GB, free: 1.707 MB / 0.545 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006193/0.916, allocations: 3.25 MB / 0.7611 GB, free: 14.44 MB / 0.5606 GB Notification: Performance of collectPreVariables (initialization): time 0.00102/0.9171, allocations: 85.7 kB / 0.7612 GB, free: 14.35 MB / 0.5606 GB Notification: Performance of collectInitialEqns (initialization): time 0.001739/0.9188, allocations: 1.982 MB / 0.7631 GB, free: 12.35 MB / 0.5606 GB Notification: Performance of collectInitialBindings (initialization): time 0.002198/0.921, allocations: 1.301 MB / 0.7644 GB, free: 11.03 MB / 0.5606 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001216/0.9223, allocations: 342.6 kB / 0.7647 GB, free: 10.69 MB / 0.5606 GB Notification: Performance of setup shared object (initialization): time 9.264e-05/0.9224, allocations: 305.1 kB / 0.765 GB, free: 10.39 MB / 0.5606 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.006943/0.9293, allocations: 2.862 MB / 0.7678 GB, free: 7.516 MB / 0.5606 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.007328/0.9367, allocations: 3.495 MB / 0.7712 GB, free: 3.793 MB / 0.5606 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.3319/1.269, allocations: 20.88 MB / 0.7916 GB, free: 157.1 MB / 0.5606 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.038e-05/1.269, allocations: 4.156 kB / 0.7916 GB, free: 157.1 MB / 0.5606 GB Notification: Performance of matching and sorting (n=532) (initialization): time 0.06242/1.331, allocations: 21.06 MB / 0.8121 GB, free: 153 MB / 0.5606 GB Notification: Performance of prepare postOptimizeDAE: time 0.000323/1.331, allocations: 0.5853 MB / 0.8127 GB, free: 152.2 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.721e-05/1.331, allocations: 15.72 kB / 0.8127 GB, free: 152.2 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.02649/1.358, allocations: 8.479 MB / 0.821 GB, free: 149.4 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003545/1.362, allocations: 0.672 MB / 0.8217 GB, free: 148.8 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08462/1.446, allocations: 34.89 MB / 0.8557 GB, free: 115.3 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00772/1.454, allocations: 0.7421 MB / 0.8565 GB, free: 114.6 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001025/1.455, allocations: 156 kB / 0.8566 GB, free: 114.5 MB / 0.5606 GB Notification: Performance of prepare postOptimizeDAE: time 1.203e-05/1.455, allocations: 4 kB / 0.8566 GB, free: 114.4 MB / 0.5606 GB Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.06186/1.517, allocations: 20.62 MB / 0.8768 GB, free: 93.8 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.748e-05/1.517, allocations: 12 kB / 0.8768 GB, free: 93.79 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.02741/1.544, allocations: 8.478 MB / 0.885 GB, free: 85.3 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003709/1.548, allocations: 0.667 MB / 0.8857 GB, free: 84.63 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08943/1.638, allocations: 34.89 MB / 0.9198 GB, free: 49.61 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007133/1.645, allocations: 0.7045 MB / 0.9205 GB, free: 48.93 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001184/1.646, allocations: 155.9 kB / 0.9206 GB, free: 48.77 MB / 0.5606 GB Warning: Assuming fixed start value for the following 5 variables: order5.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real order3.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real order3.delta:VARIABLE(flow=false start = order3.delta0 unit = "rad" fixed = true ) "Rotor angle" type: Real order3.e1q:VARIABLE(start = order3.e1q0 unit = "1" fixed = true ) "q-axis transient voltage" type: Real order5.delta:VARIABLE(flow=false start = order5.delta0 unit = "rad" fixed = true ) "Rotor angle" type: Real Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 65 * 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 (397): * Single equations (assignments): 394 * Array equations: 0 * Algorithm blocks: 2 * 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): 1 system {(92,44,5.8%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.003715/1.65, allocations: 0.7444 MB / 0.9213 GB, free: 48.02 MB / 0.5606 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001951/1.652, allocations: 454.4 kB / 0.9218 GB, free: 47.57 MB / 0.5606 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.06038/1.712, allocations: 20.55 MB / 0.9418 GB, free: 26.95 MB / 0.5606 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.286e-05/1.712, allocations: 40 kB / 0.9419 GB, free: 26.91 MB / 0.5606 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0001065/1.712, allocations: 33.58 kB / 0.9419 GB, free: 26.88 MB / 0.5606 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.000123/1.712, allocations: 19.88 kB / 0.9419 GB, free: 26.86 MB / 0.5606 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.07052/1.783, allocations: 25.85 MB / 0.9672 GB, free: 0.9219 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.541e-05/1.783, allocations: 0 / 0.9672 GB, free: 0.9219 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002582/1.786, allocations: 411.5 kB / 0.9676 GB, free: 0.5195 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.02916/1.815, allocations: 8.58 MB / 0.9759 GB, free: 7.93 MB / 0.5762 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.00101/1.816, allocations: 75.98 kB / 0.976 GB, free: 7.855 MB / 0.5762 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.3557/2.172, allocations: 30.67 MB / 1.006 GB, free: 176.2 MB / 0.5919 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.769e-06/2.172, allocations: 2.344 kB / 1.006 GB, free: 176.2 MB / 0.5919 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.07426/2.246, allocations: 24.19 MB / 1.03 GB, free: 173.2 MB / 0.5919 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001775/2.248, allocations: 0.5558 MB / 1.03 GB, free: 172.8 MB / 0.5919 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001227/2.249, allocations: 75.86 kB / 1.03 GB, free: 172.7 MB / 0.5919 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004744/2.254, allocations: 0.4961 MB / 1.031 GB, free: 172.7 MB / 0.5919 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002677/2.256, allocations: 0.7576 MB / 1.031 GB, free: 172.6 MB / 0.5919 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002964/2.257, allocations: 92.38 kB / 1.032 GB, free: 172.6 MB / 0.5919 GB Notification: Performance of sorting global known variables: time 0.002755/2.259, allocations: 2.134 MB / 1.034 GB, free: 171.8 MB / 0.5919 GB Notification: Performance of sort global known variables: time 1.7e-07/2.26, allocations: 0 / 1.034 GB, free: 171.8 MB / 0.5919 GB Notification: Performance of remove unused functions: time 0.008232/2.268, allocations: 2.977 MB / 1.037 GB, free: 171.3 MB / 0.5919 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 2 * Number of states: 12 (order3.delta,order3.w,order3.e1q,order5.delta,order5.w,order5.e1q,order5.e2q,order5.e2d,aVRTypeI.firstOrder.y,aVRTypeI.firstOrder2.y,aVRTypeI.transferFunction.x_scaled[1],aVRTypeI.transferFunction.x_scaled[2]) * 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 (251): * Single equations (assignments): 249 * Array equations: 0 * Algorithm blocks: 1 * 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): 1 system {(96,40,5.5%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.0006285/2.268, allocations: 197 kB / 1.037 GB, free: 171.1 MB / 0.5919 GB Notification: Performance of simCode: created initialization part: time 0.04504/2.313, allocations: 17.73 MB / 1.054 GB, free: 161.3 MB / 0.5919 GB Notification: Performance of simCode: created event and clocks part: time 6.662e-06/2.313, allocations: 5.422 kB / 1.054 GB, free: 161.3 MB / 0.5919 GB Notification: Performance of simCode: created simulation system equations: time 0.01916/2.333, allocations: 6.883 MB / 1.061 GB, free: 155.4 MB / 0.5919 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005044/2.338, allocations: 381.8 kB / 1.061 GB, free: 155 MB / 0.5919 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01451/2.352, allocations: 10.09 MB / 1.071 GB, free: 145.6 MB / 0.5919 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001496/2.354, allocations: 2.5 MB / 1.073 GB, free: 143.7 MB / 0.5919 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001452/2.355, allocations: 262.6 kB / 1.074 GB, free: 143.4 MB / 0.5919 GB Notification: Performance of SimCode: time 7.21e-07/2.355, allocations: 0 / 1.074 GB, free: 143.4 MB / 0.5919 GB Notification: Performance of Templates: time 0.09538/2.451, allocations: 83.59 MB / 1.155 GB, free: 62.68 MB / 0.5919 GB make -j1 -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.makefile (rm -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.sim & ./OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe 2>&1)