Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+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/ModelicaServices 3.2.3+maint.om/package.mo", uses=false)
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/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 Modelica_Synchronous with version 0.93.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo)
Using package ModelicaServices with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+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 Complex with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Running command: translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.007462/0.007462, allocations: 170.8 kB / 15.11 MB, free: 5.973 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.478/1.478, allocations: 205.4 MB / 221.3 MB, free: 13.82 MB / 186.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001026/0.001026, allocations: 94.88 kB / 269.6 MB, free: 13.35 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_Synchronous 0.93.0-master/package.mo): time 0.06654/0.06654, allocations: 12.59 MB / 330.4 MB, free: 10.28 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 2.0.0/package.mo): time 0.4631/0.4631, allocations: 78.52 MB / 460 MB, free: 3.438 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004969/0.000497, allocations: 18.33 kB / 0.5285 GB, free: 35.16 MB / 362.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1317/0.1322, allocations: 69.33 MB / 0.5962 GB, free: 3.805 MB / 378.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 0.4234/0.5557, allocations: 100.3 MB / 0.6941 GB, free: 35.16 MB / 474.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002508/0.556, allocations: 86.14 kB / 0.6942 GB, free: 35.16 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 0.1359/0.6919, allocations: 59.28 MB / 0.7521 GB, free: 23.84 MB / 474.7 MB
Notification: Performance of FrontEnd: time 1.893e-06/0.692, allocations: 0 / 0.7521 GB, free: 23.84 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 0.0001157/0.6921, allocations: 50.88 kB / 0.7521 GB, free: 23.84 MB / 474.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 487
 * Number of variables: 487
Notification: Performance of Generate backend data structure: time 0.01152/0.7036, allocations: 4.23 MB / 0.7562 GB, free: 21.57 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.157e-05/0.7037, allocations: 11.77 kB / 0.7562 GB, free: 21.57 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.009614/0.7133, allocations: 1.582 MB / 0.7578 GB, free: 21 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003487/0.7168, allocations: 1.482 MB / 0.7592 GB, free: 20.2 MB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004556/0.7173, allocations: 0.6832 MB / 0.7599 GB, free: 19.66 MB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000355/0.7176, allocations: 194 kB / 0.7601 GB, free: 19.66 MB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005194/0.7228, allocations: 3.07 MB / 0.7631 GB, free: 18.9 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.244e-05/0.7229, allocations: 0 / 0.7631 GB, free: 18.9 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002235/0.7231, allocations: 79.66 kB / 0.7632 GB, free: 18.9 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.467e-05/0.7232, allocations: 46.88 kB / 0.7632 GB, free: 18.9 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004903/0.7281, allocations: 2.867 MB / 0.766 GB, free: 18.46 MB / 474.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.01517/0.7432, allocations: 9.606 MB / 0.7754 GB, free: 13.62 MB / 474.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01008/0.7534, allocations: 4.775 MB / 0.7801 GB, free: 12.14 MB / 474.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003352/0.7537, allocations: 43.73 kB / 0.7801 GB, free: 12.13 MB / 474.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.491e-05/0.7538, allocations: 61.19 kB / 0.7802 GB, free: 12.09 MB / 474.7 MB
Notification: Performance of pre-optimization done (n=228): time 3.948e-06/0.7538, allocations: 0 / 0.7802 GB, free: 12.09 MB / 474.7 MB
Notification: Performance of matching and sorting (n=228): time 0.09653/0.8503, allocations: 28.57 MB / 0.808 GB, free: 3.289 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.857e-05/0.8505, allocations: 124.8 kB / 0.8082 GB, free: 3.145 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002931/0.8534, allocations: 1.653 MB / 0.8098 GB, free: 1.477 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0007044/0.8541, allocations: 61.7 kB / 0.8098 GB, free: 1.41 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0007949/0.8549, allocations: 1.128 MB / 0.8109 GB, free: 276 kB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001059/0.856, allocations: 0.7894 MB / 0.8117 GB, free: 15.46 MB / 0.4948 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001763/0.8562, allocations: 40 kB / 0.8118 GB, free: 15.43 MB / 0.4948 GB
Notification: Performance of setup shared object (initialization): time 0.0001536/0.8564, allocations: 386.2 kB / 0.8121 GB, free: 15.04 MB / 0.4948 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003635/0.86, allocations: 1.8 MB / 0.8139 GB, free: 13.24 MB / 0.4948 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003823/0.8638, allocations: 2.099 MB / 0.8159 GB, free: 11.03 MB / 0.4948 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003764/0.8676, allocations: 1.91 MB / 0.8178 GB, free: 9.121 MB / 0.4948 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.025e-06/0.8676, allocations: 0 / 0.8178 GB, free: 9.121 MB / 0.4948 GB
Notification: Performance of matching and sorting (n=276) (initialization): time 0.04052/0.9082, allocations: 14.11 MB / 0.8316 GB, free: 10.98 MB / 0.5105 GB
Notification: Performance of prepare postOptimizeDAE: time 0.001219/0.9095, allocations: 2.737 MB / 0.8342 GB, free: 8.027 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.05e-05/0.9095, allocations: 8 kB / 0.8343 GB, free: 8.02 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01993/0.9294, allocations: 6.445 MB / 0.8405 GB, free: 1.566 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00212/0.9316, allocations: 323.5 kB / 0.8409 GB, free: 1.25 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0673/0.9989, allocations: 26.45 MB / 0.8667 GB, free: 6.648 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00432/1.003, allocations: 489.8 kB / 0.8672 GB, free: 6.168 MB / 0.5417 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006655/1.004, allocations: 79.97 kB / 0.8672 GB, free: 6.09 MB / 0.5417 GB
Notification: Performance of prepare postOptimizeDAE: time 1.611e-05/1.004, allocations: 4 kB / 0.8672 GB, free: 6.086 MB / 0.5417 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.04085/1.045, allocations: 13.79 MB / 0.8807 GB, free: 8.273 MB / 0.5573 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.088e-05/1.045, allocations: 8 kB / 0.8807 GB, free: 8.266 MB / 0.5573 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02014/1.065, allocations: 6.441 MB / 0.887 GB, free: 1.82 MB / 0.5573 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00198/1.067, allocations: 311.5 kB / 0.8873 GB, free: 1.516 MB / 0.5573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06933/1.137, allocations: 26.46 MB / 0.9131 GB, free: 6.914 MB / 0.5886 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004595/1.141, allocations: 458.1 kB / 0.9136 GB, free: 6.465 MB / 0.5886 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007459/1.142, allocations: 83.97 kB / 0.9137 GB, free: 6.383 MB / 0.5886 GB
Warning: Assuming fixed start value for the following 7 variables:
         Exc2.vref:VARIABLE(unit = "1" fixed = true protected = true )  type: Real
         Exc1.vref:VARIABLE(unit = "1" fixed = true protected = true )  type: Real
         order4_1.e1d:VARIABLE(start = order4_1.e1d0 unit = "1" fixed = true )  "d-axis transient voltage" type: Real
         order4_1.e1q:VARIABLE(start = order4_1.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
         order4_1.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
         order3_2.w:VARIABLE(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 (186):
 * Single equations (assignments): 185
 * Array equations: 0
 * Algorithm blocks: 0
 * 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: 0
 * Non-linear torn systems: 1 {65 26}
Notification: Performance of prepare postOptimizeDAE: time 0.002483/1.144, allocations: 493.4 kB / 0.9141 GB, free: 5.895 MB / 0.5886 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001339/1.146, allocations: 272.6 kB / 0.9144 GB, free: 5.625 MB / 0.5886 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.3431/1.489, allocations: 14.27 MB / 0.9283 GB, free: 168.8 MB / 0.5886 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.455e-05/1.489, allocations: 26.19 kB / 0.9283 GB, free: 168.8 MB / 0.5886 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.387e-05/1.489, allocations: 18.16 kB / 0.9284 GB, free: 168.8 MB / 0.5886 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.758e-05/1.489, allocations: 15.23 kB / 0.9284 GB, free: 168.8 MB / 0.5886 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.04557/1.535, allocations: 17.76 MB / 0.9457 GB, free: 168 MB / 0.5886 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.235e-05/1.535, allocations: 0 / 0.9457 GB, free: 168 MB / 0.5886 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001617/1.536, allocations: 251.2 kB / 0.946 GB, free: 168 MB / 0.5886 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0157/1.552, allocations: 6.047 MB / 0.9519 GB, free: 167.7 MB / 0.5886 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0005767/1.553, allocations: 48.91 kB / 0.9519 GB, free: 167.7 MB / 0.5886 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04554/1.598, allocations: 22.66 MB / 0.974 GB, free: 164.9 MB / 0.5886 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.446e-06/1.598, allocations: 4 kB / 0.974 GB, free: 164.9 MB / 0.5886 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.04077/1.639, allocations: 16.81 MB / 0.9905 GB, free: 151.4 MB / 0.5886 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001071/1.64, allocations: 377.8 kB / 0.9908 GB, free: 151 MB / 0.5886 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004069/1.641, allocations: 40 kB / 0.9909 GB, free: 151 MB / 0.5886 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002502/1.643, allocations: 363.6 kB / 0.9912 GB, free: 150.7 MB / 0.5886 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001794/1.645, allocations: 0.5455 MB / 0.9917 GB, free: 150.4 MB / 0.5886 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.000174/1.645, allocations: 63.38 kB / 0.9918 GB, free: 150.4 MB / 0.5886 GB
Notification: Performance of sorting global known variables: time 0.001551/1.647, allocations: 1.267 MB / 0.993 GB, free: 149.3 MB / 0.5886 GB
Notification: Performance of sort global known variables: time 1.3e-07/1.647, allocations: 0 / 0.993 GB, free: 149.3 MB / 0.5886 GB
Notification: Performance of remove unused functions: time 0.003939/1.651, allocations: 1.69 MB / 0.9947 GB, free: 147.7 MB / 0.5886 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 15 (order3_2.delta,order3_2.w,order3_2.e1q,order2.delta,order2.w,order4_1.delta,order4_1.w,order4_1.e1q,order4_1.e1d,Exc1.vm,Exc1.vr,Exc1.vf1,Exc2.vm,Exc2.vr,Exc2.vf1)
 * 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 (147):
 * Single equations (assignments): 146
 * Array equations: 0
 * Algorithm blocks: 0
 * 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 {(64,8.0%) 24}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.0003704/1.651, allocations: 182.8 kB / 0.9949 GB, free: 147.5 MB / 0.5886 GB
Notification: Performance of simCode: created initialization part: time 0.02737/1.678, allocations: 11.3 MB / 1.006 GB, free: 138 MB / 0.5886 GB
Notification: Performance of simCode: created event and clocks part: time 9.548e-06/1.678, allocations: 0 / 1.006 GB, free: 138 MB / 0.5886 GB
Notification: Performance of simCode: created simulation system equations: time 0.01205/1.69, allocations: 4.591 MB / 1.01 GB, free: 134.1 MB / 0.5886 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001678/1.692, allocations: 192 kB / 1.011 GB, free: 133.9 MB / 0.5886 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.007745/1.7, allocations: 6.175 MB / 1.017 GB, free: 128.2 MB / 0.5886 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009618/1.701, allocations: 1.789 MB / 1.018 GB, free: 126.8 MB / 0.5886 GB
Notification: Performance of simCode: alias equations: time 0.0009475/1.702, allocations: 209.4 kB / 1.019 GB, free: 126.7 MB / 0.5886 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004267/1.702, allocations: 123.1 kB / 1.019 GB, free: 126.6 MB / 0.5886 GB
Notification: Performance of SimCode: time 1.052e-06/1.702, allocations: 0 / 1.019 GB, free: 126.6 MB / 0.5886 GB
Notification: Performance of Templates: time 0.08711/1.789, allocations: 70.85 MB / 1.088 GB, free: 58.42 MB / 0.5886 GB
make -j1 -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.makefile
(rm -f OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe ; mkfifo OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe ; head -c 1048576 < OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe >> ../files/OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.sim & ./OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_2.0.0_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe 2>&1)