Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/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/Modelica 3.2.3+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 2.0.0/package.mo", uses=false)
Using package OpenIPSL with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/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 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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)
Using package Modelica_Synchronous with version 0.93.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo)
Running command: translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo): time 0.08122/0.08122, allocations: 12.61 MB / 28.91 MB, free: 0.625 MB / 19.63 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001305/0.001305, allocations: 111.5 kB / 32.51 MB, free: 3.566 MB / 26.18 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.425/1.425, allocations: 205.1 MB / 241.1 MB, free: 12.36 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.00157/0.001569, allocations: 196.4 kB / 291.4 MB, free: 10.93 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.448/0.448, allocations: 78.51 MB / 420 MB, free: 10.78 MB / 302.1 MB
Notification: Modelica_Synchronous requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: OpenIPSL requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002969/0.002969, allocations: 136.9 kB / 0.5566 GB, free: 17.24 MB / 382.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1375/0.1405, allocations: 68.11 MB / 0.6231 GB, free: 13.09 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI): time 0.01349/0.1541, allocations: 13.44 MB / 0.6362 GB, free: 15.6 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008554/0.1627, allocations: 3.449 MB / 0.6396 GB, free: 12.14 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002732/0.1655, allocations: 28 kB / 0.6396 GB, free: 12.11 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002163/0.1676, allocations: 0.5654 MB / 0.6402 GB, free: 11.54 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005685/0.1734, allocations: 1.552 MB / 0.6417 GB, free: 9.992 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004029/0.1774, allocations: 1.543 MB / 0.6432 GB, free: 8.473 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.005828/0.1832, allocations: 3.655 MB / 0.6468 GB, free: 4.801 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001601/0.1849, allocations: 0.8228 MB / 0.6476 GB, free: 3.941 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002535/0.1874, allocations: 1.49 MB / 0.649 GB, free: 2.445 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003024/0.1904, allocations: 1.944 MB / 0.6509 GB, free: 0.4922 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001101/0.1916, allocations: 280 kB / 0.6512 GB, free: 224 kB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001844/0.1934, allocations: 423.9 kB / 0.6516 GB, free: 15.8 MB / 478.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001328/0.1948, allocations: 0.7546 MB / 0.6523 GB, free: 15.05 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.00288/0.1977, allocations: 1.623 MB / 0.6539 GB, free: 13.41 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.004784/0.2025, allocations: 3.931 MB / 0.6578 GB, free: 9.469 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.011e-06/0.2025, allocations: 0 / 0.6578 GB, free: 9.469 MB / 478.1 MB
Notification: Performance of FrontEnd: time 1.903e-06/0.2025, allocations: 0 / 0.6578 GB, free: 9.469 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 6.127e-05/0.2026, allocations: 0 / 0.6578 GB, free: 9.469 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: 516
 * Number of variables: 516
Notification: Performance of Generate backend data structure: time 0.02509/0.2276, allocations: 6.539 MB / 0.6642 GB, free: 2.832 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.001e-05/0.2277, allocations: 11.98 kB / 0.6642 GB, free: 2.82 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01633/0.2441, allocations: 2.271 MB / 0.6664 GB, free: 0.5391 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004577/0.2487, allocations: 1.882 MB / 0.6682 GB, free: 14.59 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000783/0.2495, allocations: 0.6788 MB / 0.6689 GB, free: 13.81 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008772/0.2504, allocations: 208 kB / 0.6691 GB, free: 13.61 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.008178/0.2586, allocations: 3.036 MB / 0.672 GB, free: 10.48 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 5.632e-05/0.2587, allocations: 4 kB / 0.672 GB, free: 10.48 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004397/0.2591, allocations: 91.94 kB / 0.6721 GB, free: 10.39 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.036e-05/0.2592, allocations: 48 kB / 0.6722 GB, free: 10.34 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006869/0.266, allocations: 2.815 MB / 0.6749 GB, free: 7.512 MB / 494.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.3349/0.601, allocations: 9.865 MB / 0.6846 GB, free: 48.19 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0125/0.6135, allocations: 4.087 MB / 0.6886 GB, free: 47.18 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005142/0.6187, allocations: 1.971 MB / 0.6905 GB, free: 46.43 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003395/0.6191, allocations: 48.36 kB / 0.6905 GB, free: 46.42 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.443e-05/0.6191, allocations: 58.34 kB / 0.6906 GB, free: 46.37 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=241): time 6.212e-06/0.6191, allocations: 1.25 kB / 0.6906 GB, free: 46.37 MB / 494.1 MB
Notification: Performance of matching and sorting (n=241): time 0.1247/0.7439, allocations: 27.98 MB / 0.7179 GB, free: 35.66 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001068/0.744, allocations: 232.6 kB / 0.7181 GB, free: 35.4 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003976/0.748, allocations: 2.275 MB / 0.7204 GB, free: 33.81 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0007262/0.7488, allocations: 70.25 kB / 0.7204 GB, free: 33.74 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.000883/0.7497, allocations: 1.178 MB / 0.7216 GB, free: 32.75 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001257/0.7509, allocations: 0.8444 MB / 0.7224 GB, free: 31.92 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002085/0.753, allocations: 0.53 MB / 0.7229 GB, free: 31.38 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 5.244e-05/0.7531, allocations: 305.1 kB / 0.7232 GB, free: 31.08 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004317/0.7574, allocations: 1.951 MB / 0.7251 GB, free: 29.12 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004491/0.7619, allocations: 2.302 MB / 0.7274 GB, free: 26.73 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0517/0.8137, allocations: 13.96 MB / 0.741 GB, free: 12.56 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.316e-05/0.8137, allocations: 4 kB / 0.741 GB, free: 12.56 MB / 494.1 MB
Notification: Performance of matching and sorting (n=393) (initialization): time 0.04144/0.8552, allocations: 12.21 MB / 0.7529 GB, free: 280 kB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 9.541e-05/0.8553, allocations: 118.8 kB / 0.753 GB, free: 136 kB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.706e-05/0.8554, allocations: 8 kB / 0.753 GB, free: 128 kB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02111/0.8765, allocations: 5.371 MB / 0.7583 GB, free: 10.75 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003531/0.8801, allocations: 0.5149 MB / 0.7588 GB, free: 10.24 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.07067/0.9508, allocations: 23.5 MB / 0.7817 GB, free: 2.586 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004871/0.9557, allocations: 465.2 kB / 0.7822 GB, free: 2.129 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007482/0.9565, allocations: 114.2 kB / 0.7823 GB, free: 2.02 MB / 0.5137 GB
Warning: Assuming fixed start value for the following 6 variables:
         order3_2.delta:VARIABLE(flow=false start = order3_2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order3_2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3_2.e1q:VARIABLE(start = order3_2.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3_1.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.006687/0.9632, allocations: 2.276 MB / 0.7845 GB, free: 15.74 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.005433/0.9687, allocations: 2.284 MB / 0.7868 GB, free: 13.36 MB / 0.5294 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.05867/1.027, allocations: 13.89 MB / 0.8003 GB, free: 15.26 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.765e-05/1.027, allocations: 4 kB / 0.8003 GB, free: 15.25 MB / 0.545 GB
Notification: Performance of matching and sorting (n=393) (initialization_lambda0): time 0.04514/1.073, allocations: 12.17 MB / 0.8122 GB, free: 3.02 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001029/1.073, allocations: 114.8 kB / 0.8123 GB, free: 2.883 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.525e-05/1.073, allocations: 8.781 kB / 0.8123 GB, free: 2.875 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02001/1.093, allocations: 5.375 MB / 0.8176 GB, free: 13.49 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002982/1.096, allocations: 0.511 MB / 0.8181 GB, free: 12.98 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.07085/1.167, allocations: 23.51 MB / 0.841 GB, free: 5.328 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004799/1.172, allocations: 437.6 kB / 0.8414 GB, free: 4.898 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007835/1.172, allocations: 112 kB / 0.8415 GB, free: 4.789 MB / 0.5762 GB
Warning: Assuming fixed start value for the following 6 variables:
         order3_2.delta:VARIABLE(flow=false start = order3_2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order3_2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3_2.e1q:VARIABLE(start = order3_2.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3_1.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 29
 * 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 (305):
 * Single equations (assignments): 300
 * Array equations: 0
 * Algorithm blocks: 4
 * 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
   {(63,26)}
Notification: Performance of prepare postOptimizeDAE: time 0.00146/1.174, allocations: 395.9 kB / 0.8419 GB, free: 4.387 MB / 0.5762 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001439/1.175, allocations: 304.3 kB / 0.8422 GB, free: 4.086 MB / 0.5762 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04313/1.218, allocations: 11.67 MB / 0.8536 GB, free: 8.375 MB / 0.5919 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.452e-05/1.219, allocations: 15.89 kB / 0.8536 GB, free: 8.359 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.006e-05/1.219, allocations: 4 kB / 0.8536 GB, free: 8.355 MB / 0.5919 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00118/1.22, allocations: 404.7 kB / 0.854 GB, free: 7.938 MB / 0.5919 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001488/1.221, allocations: 59.92 kB / 0.8541 GB, free: 7.879 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004168/1.225, allocations: 283.7 kB / 0.8543 GB, free: 7.602 MB / 0.5919 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.005037/1.231, allocations: 1.387 MB / 0.8557 GB, free: 6.215 MB / 0.5919 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01978/1.25, allocations: 5.676 MB / 0.8612 GB, free: 0.5469 MB / 0.5919 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01485/1.265, allocations: 5.902 MB / 0.867 GB, free: 10.61 MB / 0.6075 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.004164/1.269, allocations: 1.616 MB / 0.8686 GB, free: 8.996 MB / 0.6075 GB
Notification: Performance of sorting global known variables: time 0.003257/1.273, allocations: 1.503 MB / 0.87 GB, free: 7.488 MB / 0.6075 GB
Notification: Performance of Backend: time 7.81e-07/1.273, allocations: 4 kB / 0.8701 GB, free: 7.484 MB / 0.6075 GB
Notification: Performance of simCode: created initialization part: time 0.03619/1.309, allocations: 9.92 MB / 0.8797 GB, free: 13.44 MB / 0.6231 GB
Notification: Performance of SimCode: time 0.01151/1.321, allocations: 6.189 MB / 0.8858 GB, free: 7.195 MB / 0.6231 GB
Notification: Performance of Templates: time 0.463/1.784, allocations: 90.24 MB / 0.9739 GB, free: 219.2 MB / 0.67 GB
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI.makefile
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI.sim & ./OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.ThirdOrder_AVRI.pipe 2>&1)