Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.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.PSATSystems.TwoAreaOld.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI")
translateModel(OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0008932/0.0008937, allocations: 176.1 kB / 16.08 MB, free: 5.605 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.249/1.249, allocations: 222.8 MB / 239.7 MB, free: 11.88 MB / 202.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0007137/0.0007138, allocations: 104.4 kB / 290.5 MB, free: 11.23 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4218/0.4218, allocations: 78.41 MB / 419.6 MB, free: 9.805 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0005046/0.0005047, allocations: 12 kB / 499.1 MB, free: 10.75 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3245/0.325, allocations: 68.75 MB / 0.5545 GB, free: 20.24 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI): time 0.004921/0.3299, allocations: 6.828 MB / 0.5612 GB, free: 13.38 MB / 378.7 MB
Notification: Performance of NFInst.instExpressions: time 0.002678/0.3326, allocations: 2.064 MB / 0.5632 GB, free: 11.31 MB / 378.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0003642/0.333, allocations: 12 kB / 0.5632 GB, free: 11.3 MB / 378.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0004935/0.3335, allocations: 186.8 kB / 0.5634 GB, free: 11.12 MB / 378.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.001267/0.3348, allocations: 0.7286 MB / 0.5641 GB, free: 10.39 MB / 378.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0008992/0.3357, allocations: 0.6107 MB / 0.5647 GB, free: 9.785 MB / 378.7 MB
Notification: Performance of NFFlatten.flatten: time 0.000816/0.3366, allocations: 1.519 MB / 0.5662 GB, free: 8.258 MB / 378.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002864/0.3368, allocations: 285.3 kB / 0.5664 GB, free: 7.969 MB / 378.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0004081/0.3373, allocations: 0.5715 MB / 0.567 GB, free: 7.395 MB / 378.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0005359/0.3378, allocations: 0.7659 MB / 0.5678 GB, free: 6.625 MB / 378.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.000127/0.3379, allocations: 64 kB / 0.5678 GB, free: 6.562 MB / 378.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.000323/0.3383, allocations: 187.9 kB / 0.568 GB, free: 6.379 MB / 378.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001753/0.3385, allocations: 274.6 kB / 0.5683 GB, free: 6.109 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003367/0.3388, allocations: 0.6055 MB / 0.5688 GB, free: 5.5 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.000876/0.3397, allocations: 1.582 MB / 0.5704 GB, free: 3.914 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.116e-06/0.3397, allocations: 0 / 0.5704 GB, free: 3.914 MB / 378.7 MB
Notification: Performance of FrontEnd: time 1.212e-06/0.3397, allocations: 0 / 0.5704 GB, free: 3.914 MB / 378.7 MB
Notification: Performance of Transformations before backend: time 1.249e-05/0.3397, allocations: 4 kB / 0.5704 GB, free: 3.91 MB / 378.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 188
 * Number of variables: 188
Notification: Performance of Generate backend data structure: time 0.006836/0.3466, allocations: 2.478 MB / 0.5728 GB, free: 1.367 MB / 378.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.364e-05/0.3466, allocations: 8.031 kB / 0.5728 GB, free: 1.359 MB / 378.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004474/0.3511, allocations: 0.7748 MB / 0.5736 GB, free: 0.582 MB / 378.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001163/0.3523, allocations: 0.9099 MB / 0.5745 GB, free: 15.62 MB / 394.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002173/0.3525, allocations: 230.5 kB / 0.5747 GB, free: 15.36 MB / 394.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001479/0.3526, allocations: 87.91 kB / 0.5748 GB, free: 15.27 MB / 394.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001902/0.3546, allocations: 1.099 MB / 0.5758 GB, free: 14.16 MB / 394.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.941e-05/0.3546, allocations: 3.938 kB / 0.5758 GB, free: 14.16 MB / 394.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.207e-05/0.3547, allocations: 28 kB / 0.5759 GB, free: 14.13 MB / 394.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.814e-05/0.3547, allocations: 23.98 kB / 0.5759 GB, free: 14.11 MB / 394.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001907/0.3566, allocations: 1.015 MB / 0.5769 GB, free: 13.09 MB / 394.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.00618/0.3628, allocations: 3.917 MB / 0.5807 GB, free: 8.984 MB / 394.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.006999/0.3698, allocations: 2.349 MB / 0.583 GB, free: 6.539 MB / 394.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001297/0.3711, allocations: 0.6956 MB / 0.5837 GB, free: 5.836 MB / 394.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001042/0.3712, allocations: 17 kB / 0.5837 GB, free: 5.82 MB / 394.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.963e-05/0.3713, allocations: 47.5 kB / 0.5838 GB, free: 5.762 MB / 394.7 MB
Notification: Performance of pre-optimization done (n=94): time 2.444e-06/0.3713, allocations: 0 / 0.5838 GB, free: 5.762 MB / 394.7 MB
Notification: Performance of matching and sorting (n=94): time 0.02553/0.3968, allocations: 6.728 MB / 0.5903 GB, free: 15 MB / 410.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.899e-05/0.3969, allocations: 141.5 kB / 0.5905 GB, free: 14.83 MB / 410.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001569/0.3985, allocations: 1.119 MB / 0.5915 GB, free: 13.7 MB / 410.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001362/0.3986, allocations: 49.64 kB / 0.5916 GB, free: 13.64 MB / 410.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003804/0.399, allocations: 0.489 MB / 0.5921 GB, free: 13.14 MB / 410.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.000313/0.3994, allocations: 355.1 kB / 0.5924 GB, free: 12.79 MB / 410.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001938/0.3996, allocations: 145.6 kB / 0.5926 GB, free: 12.64 MB / 410.7 MB
Notification: Performance of setup shared object (initialization): time 8.627e-05/0.3996, allocations: 301.1 kB / 0.5928 GB, free: 12.35 MB / 410.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001578/0.4012, allocations: 0.773 MB / 0.5936 GB, free: 11.57 MB / 410.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001727/0.403, allocations: 0.9507 MB / 0.5945 GB, free: 10.58 MB / 410.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02301/0.426, allocations: 5.781 MB / 0.6002 GB, free: 4.715 MB / 410.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.654e-06/0.426, allocations: 0 / 0.6002 GB, free: 4.715 MB / 410.7 MB
Notification: Performance of matching and sorting (n=190) (initialization): time 0.01337/0.4394, allocations: 4.422 MB / 0.6045 GB, free: 268 kB / 410.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001558/0.4396, allocations: 238.7 kB / 0.6047 GB, free: 15.98 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.255e-05/0.4396, allocations: 11.98 kB / 0.6047 GB, free: 15.96 MB / 426.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005878/0.4455, allocations: 1.627 MB / 0.6063 GB, free: 14.34 MB / 426.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00127/0.4468, allocations: 271.6 kB / 0.6066 GB, free: 14.07 MB / 426.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01848/0.4653, allocations: 7.94 MB / 0.6143 GB, free: 5.98 MB / 426.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001716/0.467, allocations: 198.7 kB / 0.6145 GB, free: 5.785 MB / 426.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001282/0.4672, allocations: 55.97 kB / 0.6146 GB, free: 5.73 MB / 426.7 MB
Notification: Performance of prepare postOptimizeDAE: time 8.425e-06/0.4672, allocations: 6.188 kB / 0.6146 GB, free: 5.727 MB / 426.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.01335/0.4805, allocations: 4.265 MB / 0.6187 GB, free: 1.441 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.009e-05/0.4806, allocations: 8 kB / 0.6187 GB, free: 1.434 MB / 426.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005795/0.4864, allocations: 1.625 MB / 0.6203 GB, free: 15.8 MB / 442.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001319/0.4877, allocations: 263.6 kB / 0.6206 GB, free: 15.55 MB / 442.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01805/0.5058, allocations: 7.943 MB / 0.6283 GB, free: 7.461 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001676/0.5075, allocations: 178.9 kB / 0.6285 GB, free: 7.285 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001217/0.5076, allocations: 55.97 kB / 0.6286 GB, free: 7.23 MB / 442.7 MB
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
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order5.delta:VARIABLE(flow=false start = order5.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order5.e1q:VARIABLE(start = order5.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 13
 * 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 (157):
 * Single equations (assignments): 154
 * 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
   {(19,15,24.4%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0006564/0.5083, allocations: 383.6 kB / 0.6289 GB, free: 6.84 MB / 442.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002363/0.5085, allocations: 126.4 kB / 0.6291 GB, free: 6.715 MB / 442.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01205/0.5206, allocations: 3.79 MB / 0.6328 GB, free: 2.895 MB / 442.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.959e-05/0.5206, allocations: 3.984 kB / 0.6328 GB, free: 2.891 MB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.838e-06/0.5207, allocations: 0 / 0.6328 GB, free: 2.891 MB / 442.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003739/0.521, allocations: 227.2 kB / 0.633 GB, free: 2.648 MB / 442.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001899/0.5212, allocations: 24 kB / 0.633 GB, free: 2.625 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001137/0.5224, allocations: 99.88 kB / 0.6331 GB, free: 2.527 MB / 442.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009179/0.5233, allocations: 408.2 kB / 0.6335 GB, free: 2.129 MB / 442.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005714/0.529, allocations: 2.193 MB / 0.6356 GB, free: 15.9 MB / 458.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.0042/0.5332, allocations: 2.309 MB / 0.6379 GB, free: 13.57 MB / 458.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001228/0.5345, allocations: 0.5894 MB / 0.6385 GB, free: 12.98 MB / 458.7 MB
Notification: Performance of sorting global known variables: time 0.001131/0.5356, allocations: 0.7551 MB / 0.6392 GB, free: 12.22 MB / 458.7 MB
Notification: Performance of Backend: time 2.91e-07/0.5356, allocations: 4 kB / 0.6392 GB, free: 12.22 MB / 458.7 MB
Notification: Performance of simCode: created initialization part: time 0.01037/0.546, allocations: 4.024 MB / 0.6431 GB, free: 8.109 MB / 458.7 MB
Notification: Performance of SimCode: time 0.003755/0.5498, allocations: 3.357 MB / 0.6464 GB, free: 4.695 MB / 458.7 MB
Notification: Performance of Templates: time 0.04297/0.5928, allocations: 30.85 MB / 0.6765 GB, free: 5.848 MB / 490.7 MB
make -j1 -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.makefile
(rm -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe >> ../files/OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.sim & ./OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FifthOrder_AVRI.pipe 2>&1)