Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_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.SixthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_AVRI")
translateModel(OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_AVRI")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.004961/0.00496, allocations: 170.8 kB / 15.11 MB, free: 5.984 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.311/1.311, allocations: 223.2 MB / 239 MB, free: 1.766 MB / 186.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008762/0.0008762, allocations: 92.45 kB / 290.2 MB, free: 13.93 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4237/0.4237, allocations: 78.58 MB / 419.9 MB, free: 44.21 MB / 330.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003355/0.0003355, allocations: 8 kB / 499.8 MB, free: 2.949 MB / 362.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3209/0.3213, allocations: 69.34 MB / 0.5558 GB, free: 38.83 MB / 426.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 0.1142/0.4355, allocations: 99.08 MB / 0.6525 GB, free: 7.438 MB / 458.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002203/0.4358, allocations: 83.94 kB / 0.6526 GB, free: 7.355 MB / 458.7 MB
Notification: Performance of FrontEnd - DAE generated: time 0.08601/0.5218, allocations: 31.34 MB / 0.6832 GB, free: 7.859 MB / 490.7 MB
Notification: Performance of FrontEnd: time 1.723e-06/0.5218, allocations: 0 / 0.6832 GB, free: 7.859 MB / 490.7 MB
Notification: Performance of Transformations before backend: time 0.00011/0.522, allocations: 24 kB / 0.6832 GB, free: 7.836 MB / 490.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 189
 * Number of variables: 189
Notification: Performance of Generate backend data structure: time 0.006095/0.5281, allocations: 2.076 MB / 0.6853 GB, free: 5.695 MB / 490.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.912e-05/0.5281, allocations: 11.97 kB / 0.6853 GB, free: 5.684 MB / 490.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003638/0.5318, allocations: 0.568 MB / 0.6858 GB, free: 5.113 MB / 490.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001853/0.5336, allocations: 0.8351 MB / 0.6866 GB, free: 4.223 MB / 490.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002824/0.5339, allocations: 230.5 kB / 0.6869 GB, free: 3.969 MB / 490.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002551/0.5342, allocations: 87.94 kB / 0.6869 GB, free: 3.883 MB / 490.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002478/0.5367, allocations: 1.165 MB / 0.6881 GB, free: 2.699 MB / 490.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.356e-05/0.5367, allocations: 4 kB / 0.6881 GB, free: 2.695 MB / 490.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001155/0.5368, allocations: 28 kB / 0.6881 GB, free: 2.668 MB / 490.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.615e-05/0.5369, allocations: 20 kB / 0.6881 GB, free: 2.648 MB / 490.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002205/0.5391, allocations: 1.078 MB / 0.6892 GB, free: 1.57 MB / 490.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.007184/0.5463, allocations: 3.959 MB / 0.6931 GB, free: 13.42 MB / 0.4948 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.007842/0.5541, allocations: 2.905 MB / 0.6959 GB, free: 10.42 MB / 0.4948 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001125/0.5543, allocations: 16.98 kB / 0.6959 GB, free: 10.41 MB / 0.4948 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.918e-05/0.5543, allocations: 47.55 kB / 0.696 GB, free: 10.35 MB / 0.4948 GB
Notification: Performance of pre-optimization done (n=95): time 2.575e-06/0.5543, allocations: 7.938 kB / 0.696 GB, free: 10.34 MB / 0.4948 GB
Notification: Performance of matching and sorting (n=95): time 0.02848/0.5828, allocations: 8.151 MB / 0.7039 GB, free: 2.148 MB / 0.4948 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.373e-05/0.5829, allocations: 141.5 kB / 0.7041 GB, free: 1.98 MB / 0.4948 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001915/0.5848, allocations: 0.9536 MB / 0.705 GB, free: 1.016 MB / 0.4948 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002305/0.5851, allocations: 41.7 kB / 0.705 GB, free: 0.9688 MB / 0.4948 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004146/0.5855, allocations: 0.5209 MB / 0.7055 GB, free: 452 kB / 0.4948 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0004658/0.586, allocations: 339 kB / 0.7059 GB, free: 108 kB / 0.4948 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 9.827e-05/0.5861, allocations: 20 kB / 0.7059 GB, free: 88 kB / 0.4948 GB
Notification: Performance of setup shared object (initialization): time 0.0001482/0.5862, allocations: 345.9 kB / 0.7062 GB, free: 15.74 MB / 0.5105 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001484/0.5877, allocations: 0.654 MB / 0.7068 GB, free: 15.09 MB / 0.5105 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001474/0.5892, allocations: 0.8041 MB / 0.7076 GB, free: 14.24 MB / 0.5105 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001486/0.5907, allocations: 0.7541 MB / 0.7084 GB, free: 13.48 MB / 0.5105 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.548e-06/0.5907, allocations: 0 / 0.7084 GB, free: 13.48 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=122) (initialization): time 0.01834/0.6091, allocations: 4.782 MB / 0.713 GB, free: 8.664 MB / 0.5105 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0006163/0.6097, allocations: 1.204 MB / 0.7142 GB, free: 7.371 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.589e-05/0.6098, allocations: 4 kB / 0.7142 GB, free: 7.367 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01101/0.6208, allocations: 1.938 MB / 0.7161 GB, free: 5.422 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001432/0.6223, allocations: 159.8 kB / 0.7163 GB, free: 5.266 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03665/0.6589, allocations: 10.62 MB / 0.7266 GB, free: 10.39 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002425/0.6614, allocations: 195.2 kB / 0.7268 GB, free: 10.2 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001652/0.6616, allocations: 39.98 kB / 0.7269 GB, free: 10.16 MB / 0.5261 GB
Notification: Performance of prepare postOptimizeDAE: time 1.426e-05/0.6616, allocations: 8 kB / 0.7269 GB, free: 10.15 MB / 0.5261 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.02224/0.6838, allocations: 4.61 MB / 0.7314 GB, free: 5.512 MB / 0.5261 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.461e-05/0.6839, allocations: 4 kB / 0.7314 GB, free: 5.508 MB / 0.5261 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01045/0.6944, allocations: 1.939 MB / 0.7333 GB, free: 3.562 MB / 0.5261 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009769/0.6954, allocations: 147.8 kB / 0.7334 GB, free: 3.418 MB / 0.5261 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02132/0.7167, allocations: 10.6 MB / 0.7438 GB, free: 8.539 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001646/0.7184, allocations: 187.3 kB / 0.7439 GB, free: 8.355 MB / 0.5417 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.496e-05/0.7185, allocations: 35.98 kB / 0.744 GB, free: 8.32 MB / 0.5417 GB
Warning: Assuming fixed start value for the following 4 variables:
         order6.w:VARIABLE(start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order2.w:VARIABLE(start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order6.e2d:VARIABLE(start = order6.e2d0 unit = "1" fixed = true )  "d-axis sub-transient voltage" type: Real
         order6.delta:VARIABLE(start = order6.delta0 unit = "rad" fixed = true )  "Rotor angle" 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 (90):
 * Single equations (assignments): 86
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(1,100.0%) 1}
 * Non-linear torn systems: 1 {18 14}
Notification: Performance of prepare postOptimizeDAE: time 0.0006581/0.7192, allocations: 368 kB / 0.7443 GB, free: 7.945 MB / 0.5417 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002417/0.7194, allocations: 126.4 kB / 0.7444 GB, free: 7.82 MB / 0.5417 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0131/0.7325, allocations: 4.658 MB / 0.749 GB, free: 3.137 MB / 0.5417 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.239e-05/0.7326, allocations: 12 kB / 0.749 GB, free: 3.125 MB / 0.5417 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.642e-05/0.7326, allocations: 11.94 kB / 0.749 GB, free: 3.113 MB / 0.5417 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.592e-05/0.7326, allocations: 3.984 kB / 0.749 GB, free: 3.109 MB / 0.5417 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.01524/0.7479, allocations: 6.319 MB / 0.7552 GB, free: 12.68 MB / 0.5573 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.005e-06/0.7479, allocations: 3.938 kB / 0.7552 GB, free: 12.68 MB / 0.5573 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0007994/0.7487, allocations: 115.8 kB / 0.7553 GB, free: 12.57 MB / 0.5573 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.005325/0.754, allocations: 1.79 MB / 0.757 GB, free: 10.77 MB / 0.5573 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001782/0.7542, allocations: 27.98 kB / 0.7571 GB, free: 10.75 MB / 0.5573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01693/0.7712, allocations: 8.004 MB / 0.7649 GB, free: 2.598 MB / 0.5573 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.649e-06/0.7712, allocations: 3.953 kB / 0.7649 GB, free: 2.594 MB / 0.5573 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.2345/1.006, allocations: 5.595 MB / 0.7704 GB, free: 144.3 MB / 0.5573 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005346/1.006, allocations: 232.5 kB / 0.7706 GB, free: 144.2 MB / 0.5573 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001954/1.007, allocations: 22.19 kB / 0.7706 GB, free: 144.2 MB / 0.5573 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001273/1.008, allocations: 136 kB / 0.7707 GB, free: 144.2 MB / 0.5573 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005509/1.008, allocations: 186 kB / 0.7709 GB, free: 144.2 MB / 0.5573 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.973e-05/1.008, allocations: 26.7 kB / 0.7709 GB, free: 144.2 MB / 0.5573 GB
Notification: Performance of sorting global known variables: time 0.001165/1.01, allocations: 0.7576 MB / 0.7717 GB, free: 144.1 MB / 0.5573 GB
Notification: Performance of sort global known variables: time 1.61e-07/1.01, allocations: 0 / 0.7717 GB, free: 144.1 MB / 0.5573 GB
Notification: Performance of remove unused functions: time 0.001882/1.012, allocations: 0.6916 MB / 0.7723 GB, free: 144.1 MB / 0.5573 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 12 (order2.delta,order2.w,order6.delta,order6.w,order6.e1q,order6.e1d,order6.e2q,order6.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 (69):
 * Single equations (assignments): 67
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(20,23.0%) 12}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.000263/1.012, allocations: 161.1 kB / 0.7725 GB, free: 144 MB / 0.5573 GB
Notification: Performance of simCode: created initialization part: time 0.01016/1.022, allocations: 4.516 MB / 0.7769 GB, free: 143.2 MB / 0.5573 GB
Notification: Performance of simCode: created event and clocks part: time 5.11e-06/1.022, allocations: 0 / 0.7769 GB, free: 143.2 MB / 0.5573 GB
Notification: Performance of simCode: created simulation system equations: time 0.003992/1.026, allocations: 1.687 MB / 0.7786 GB, free: 142.9 MB / 0.5573 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0009136/1.027, allocations: 111.2 kB / 0.7787 GB, free: 142.9 MB / 0.5573 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003962/1.031, allocations: 2.895 MB / 0.7815 GB, free: 141.7 MB / 0.5573 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004925/1.031, allocations: 1.167 MB / 0.7826 GB, free: 141 MB / 0.5573 GB
Notification: Performance of simCode: alias equations: time 0.0005847/1.032, allocations: 124.5 kB / 0.7828 GB, free: 141 MB / 0.5573 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002508/1.032, allocations: 66.28 kB / 0.7828 GB, free: 141 MB / 0.5573 GB
Notification: Performance of SimCode: time 7.82e-07/1.032, allocations: 0 / 0.7828 GB, free: 141 MB / 0.5573 GB
Notification: Performance of Templates: time 0.04058/1.073, allocations: 27.03 MB / 0.8092 GB, free: 139.5 MB / 0.5573 GB
make -j1 -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_AVRI.makefile
(rm -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_AVRI.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_AVRI.pipe >> ../files/OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_AVRI.sim & ./OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_AVRI -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.SixthOrder_AVRI.pipe 2>&1)