Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_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.ThreeArea.SixthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=40000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=40000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001116/0.001116, allocations: 111 kB / 16.42 MB, free: 6.461 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.328/1.328, allocations: 222.9 MB / 240.1 MB, free: 4.379 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.001396/0.001396, allocations: 192.5 kB / 290.4 MB, free: 2.117 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5307/0.5307, allocations: 91.89 MB / 432.4 MB, free: 9.164 MB / 302.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001486/0.001486, allocations: 82.31 kB / 0.5735 GB, free: 33.37 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1183/0.1198, allocations: 71.28 MB / 0.6431 GB, free: 3.496 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI): time 0.01247/0.1323, allocations: 13.66 MB / 0.6564 GB, free: 5.777 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.007205/0.1395, allocations: 3.539 MB / 0.6599 GB, free: 2.223 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001576/0.1411, allocations: 31.94 kB / 0.6599 GB, free: 2.191 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.00129/0.1424, allocations: 0.5654 MB / 0.6604 GB, free: 1.621 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003748/0.1462, allocations: 1.567 MB / 0.662 GB, free: 48 kB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00345/0.1497, allocations: 1.564 MB / 0.6635 GB, free: 14.5 MB / 478.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004844/0.1545, allocations: 3.738 MB / 0.6672 GB, free: 10.75 MB / 478.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001804/0.1563, allocations: 0.8345 MB / 0.668 GB, free: 9.883 MB / 478.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002474/0.1588, allocations: 1.521 MB / 0.6695 GB, free: 8.355 MB / 478.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00269/0.1615, allocations: 1.995 MB / 0.6714 GB, free: 6.352 MB / 478.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0009402/0.1625, allocations: 288 kB / 0.6717 GB, free: 6.07 MB / 478.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0013/0.1638, allocations: 427.9 kB / 0.6721 GB, free: 5.652 MB / 478.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001095/0.1649, allocations: 0.7819 MB / 0.6728 GB, free: 4.867 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002269/0.1672, allocations: 1.643 MB / 0.6745 GB, free: 3.215 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.005188/0.1724, allocations: 4.005 MB / 0.6784 GB, free: 15.2 MB / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.833e-06/0.1725, allocations: 4 kB / 0.6784 GB, free: 15.19 MB / 494.1 MB
Notification: Performance of FrontEnd: time 1.874e-06/0.1725, allocations: 0 / 0.6784 GB, free: 15.19 MB / 494.1 MB
Notification: Performance of Transformations before backend: time 0.0001949/0.1727, allocations: 0 / 0.6784 GB, free: 15.19 MB / 494.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 519
 * Number of variables: 519
Notification: Performance of Generate backend data structure: time 0.02571/0.1984, allocations: 6.633 MB / 0.6848 GB, free: 8.461 MB / 494.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.448e-05/0.1984, allocations: 8.031 kB / 0.6849 GB, free: 8.453 MB / 494.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01631/0.2148, allocations: 2.275 MB / 0.6871 GB, free: 6.172 MB / 494.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004189/0.219, allocations: 1.914 MB / 0.6889 GB, free: 4.191 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007027/0.2197, allocations: 0.6711 MB / 0.6896 GB, free: 3.418 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006035/0.2203, allocations: 215.9 kB / 0.6898 GB, free: 3.207 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.007276/0.2276, allocations: 3.068 MB / 0.6928 GB, free: 56 kB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.497e-05/0.2277, allocations: 0 / 0.6928 GB, free: 56 kB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002846/0.228, allocations: 88.02 kB / 0.6929 GB, free: 15.96 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.63e-05/0.228, allocations: 52 kB / 0.6929 GB, free: 15.91 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006601/0.2346, allocations: 2.823 MB / 0.6957 GB, free: 13.07 MB / 0.4981 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 preOpt removeSimpleEquations (simulation): time 0.01988/0.2545, allocations: 9.984 MB / 0.7054 GB, free: 2.652 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01168/0.2662, allocations: 4.124 MB / 0.7095 GB, free: 14.38 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004467/0.2707, allocations: 1.996 MB / 0.7114 GB, free: 12.39 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002858/0.2711, allocations: 47.12 kB / 0.7115 GB, free: 12.34 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.132e-05/0.2711, allocations: 60.5 kB / 0.7115 GB, free: 12.27 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=244): time 4.939e-06/0.2711, allocations: 0 / 0.7115 GB, free: 12.27 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=244): time 0.1324/0.4036, allocations: 29 MB / 0.7398 GB, free: 15.19 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001237/0.4037, allocations: 243.5 kB / 0.7401 GB, free: 14.9 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003917/0.4076, allocations: 2.313 MB / 0.7423 GB, free: 12.57 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.000608/0.4083, allocations: 69.7 kB / 0.7424 GB, free: 12.5 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009683/0.4093, allocations: 1.184 MB / 0.7436 GB, free: 11.3 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00125/0.4105, allocations: 0.8703 MB / 0.7444 GB, free: 10.42 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00168/0.4122, allocations: 0.5382 MB / 0.7449 GB, free: 9.871 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0001016/0.4123, allocations: 309.1 kB / 0.7452 GB, free: 9.566 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004824/0.4171, allocations: 1.984 MB / 0.7472 GB, free: 7.574 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005102/0.4223, allocations: 2.346 MB / 0.7495 GB, free: 5.129 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0609/0.4832, allocations: 14.33 MB / 0.7634 GB, free: 6.562 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.026e-05/0.4833, allocations: 4 kB / 0.7635 GB, free: 6.559 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=405) (initialization): time 0.3106/0.7938, allocations: 12.44 MB / 0.7756 GB, free: 107.6 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 6.7e-05/0.7939, allocations: 116.3 kB / 0.7757 GB, free: 107.4 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.998e-05/0.794, allocations: 6.906 kB / 0.7757 GB, free: 107.4 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01993/0.8139, allocations: 5.446 MB / 0.781 GB, free: 107.3 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00294/0.8169, allocations: 0.5385 MB / 0.7816 GB, free: 107.3 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05841/0.8753, allocations: 23.63 MB / 0.8046 GB, free: 98.84 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004297/0.8796, allocations: 471.5 kB / 0.8051 GB, free: 98.7 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006234/0.8803, allocations: 114 kB / 0.8052 GB, free: 98.66 MB / 0.5606 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
         Syn2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.005052/0.8854, allocations: 2.324 MB / 0.8075 GB, free: 96.46 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.004601/0.89, allocations: 2.319 MB / 0.8097 GB, free: 94.04 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.05487/0.9449, allocations: 14.27 MB / 0.8237 GB, free: 79.55 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.152e-05/0.9449, allocations: 4 kB / 0.8237 GB, free: 79.55 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=405) (initialization_lambda0): time 0.04053/0.9855, allocations: 12.39 MB / 0.8358 GB, free: 67.11 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 5.326e-05/0.9855, allocations: 118.8 kB / 0.8359 GB, free: 66.97 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.728e-05/0.9856, allocations: 9.25 kB / 0.8359 GB, free: 66.96 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01798/1.004, allocations: 5.436 MB / 0.8412 GB, free: 61.52 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002684/1.006, allocations: 0.5266 MB / 0.8417 GB, free: 61 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05862/1.065, allocations: 23.65 MB / 0.8648 GB, free: 37.22 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004245/1.069, allocations: 444.3 kB / 0.8652 GB, free: 36.84 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003731/1.07, allocations: 108 kB / 0.8653 GB, free: 36.73 MB / 0.5606 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
         Syn2.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 (316):
 * Single equations (assignments): 311
 * 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,27)}
Notification: Performance of prepare postOptimizeDAE: time 0.0007194/1.07, allocations: 395 kB / 0.8657 GB, free: 36.33 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006129/1.071, allocations: 296.3 kB / 0.866 GB, free: 36.04 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03868/1.11, allocations: 11.73 MB / 0.8774 GB, free: 24.27 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.751e-05/1.11, allocations: 15.89 kB / 0.8775 GB, free: 24.25 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.324e-06/1.11, allocations: 8.75 kB / 0.8775 GB, free: 24.25 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007658/1.11, allocations: 408.1 kB / 0.8779 GB, free: 23.83 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0006979/1.111, allocations: 51.98 kB / 0.8779 GB, free: 23.78 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003051/1.114, allocations: 283.7 kB / 0.8782 GB, free: 23.5 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003871/1.118, allocations: 1.383 MB / 0.8795 GB, free: 22.11 MB / 0.5606 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01616/1.134, allocations: 5.784 MB / 0.8852 GB, free: 16.34 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01158/1.146, allocations: 5.973 MB / 0.891 GB, free: 10.33 MB / 0.5606 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003452/1.149, allocations: 1.636 MB / 0.8926 GB, free: 8.699 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.00204/1.151, allocations: 1.546 MB / 0.8941 GB, free: 7.148 MB / 0.5606 GB
Notification: Performance of Backend: time 5.91e-07/1.151, allocations: 0 / 0.8941 GB, free: 7.148 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.03111/1.183, allocations: 10.1 MB / 0.904 GB, free: 12.95 MB / 0.5762 GB
Notification: Performance of SimCode: time 0.01038/1.193, allocations: 6.372 MB / 0.9102 GB, free: 6.527 MB / 0.5762 GB
Notification: Performance of Templates: time 0.1412/1.334, allocations: 92.19 MB / 1 GB, free: 10.37 MB / 0.67 GB
make -j1 -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI.makefile
(rm -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI.sim & ./OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRI.pipe 2>&1)