Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII.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.ThreeArea.FifthOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.00459/0.00459, allocations: 170.1 kB / 15.11 MB, free: 5.988 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.184/1.184, allocations: 223.2 MB / 239 MB, free: 1.773 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.0009838/0.0009839, allocations: 102.6 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.427/0.427, allocations: 79.43 MB / 420.8 MB, free: 43.7 MB / 330.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003572/0.0003572, allocations: 12 kB / 0.489 GB, free: 2.031 MB / 362.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3019/0.3023, allocations: 69.49 MB / 0.5569 GB, free: 39.19 MB / 426.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 0.109/0.4114, allocations: 99.66 MB / 0.6542 GB, free: 5.914 MB / 458.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.000206/0.4116, allocations: 83.95 kB / 0.6543 GB, free: 5.832 MB / 458.7 MB
Notification: Performance of FrontEnd - DAE generated: time 0.1494/0.5611, allocations: 62.7 MB / 0.7155 GB, free: 6.777 MB / 0.5105 GB
Notification: Performance of FrontEnd: time 1.734e-06/0.5611, allocations: 0 / 0.7155 GB, free: 6.777 MB / 0.5105 GB
Notification: Performance of Transformations before backend: time 0.0001325/0.5612, allocations: 48 kB / 0.7156 GB, free: 6.73 MB / 0.5105 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 488
 * Number of variables: 488
Notification: Performance of Generate backend data structure: time 0.01168/0.5729, allocations: 4.283 MB / 0.7198 GB, free: 2.383 MB / 0.5105 GB
Notification: Performance of prepare preOptimizeDAE: time 3.502e-05/0.573, allocations: 8.031 kB / 0.7198 GB, free: 2.375 MB / 0.5105 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008697/0.5817, allocations: 1.592 MB / 0.7213 GB, free: 0.7773 MB / 0.5105 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002592/0.5843, allocations: 1.491 MB / 0.7228 GB, free: 15.22 MB / 0.5261 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005375/0.5848, allocations: 0.6826 MB / 0.7235 GB, free: 14.44 MB / 0.5261 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003241/0.5852, allocations: 195.9 kB / 0.7236 GB, free: 14.25 MB / 0.5261 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005422/0.5906, allocations: 3.082 MB / 0.7267 GB, free: 11.14 MB / 0.5261 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.865e-05/0.5906, allocations: 0 / 0.7267 GB, free: 11.14 MB / 0.5261 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002316/0.5909, allocations: 80 kB / 0.7267 GB, free: 11.07 MB / 0.5261 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.168e-05/0.5909, allocations: 51.95 kB / 0.7268 GB, free: 11.02 MB / 0.5261 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005266/0.5962, allocations: 2.873 MB / 0.7296 GB, free: 8.141 MB / 0.5261 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.01581/0.612, allocations: 9.674 MB / 0.739 GB, free: 14.04 MB / 0.5417 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01103/0.623, allocations: 4.784 MB / 0.7437 GB, free: 9.137 MB / 0.5417 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002197/0.6233, allocations: 41.95 kB / 0.7437 GB, free: 9.098 MB / 0.5417 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.185e-05/0.6233, allocations: 56.56 kB / 0.7438 GB, free: 9.035 MB / 0.5417 GB
Notification: Performance of pre-optimization done (n=229): time 3.967e-06/0.6233, allocations: 0 / 0.7438 GB, free: 9.035 MB / 0.5417 GB
Notification: Performance of matching and sorting (n=229): time 0.3206/0.9439, allocations: 28.7 MB / 0.7718 GB, free: 120.1 MB / 0.5573 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.096e-05/0.944, allocations: 129.5 kB / 0.772 GB, free: 120 MB / 0.5573 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003021/0.9471, allocations: 1.687 MB / 0.7736 GB, free: 119.8 MB / 0.5573 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005395/0.9476, allocations: 65.23 kB / 0.7737 GB, free: 119.8 MB / 0.5573 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0007546/0.9484, allocations: 1.136 MB / 0.7748 GB, free: 119.7 MB / 0.5573 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0009739/0.9494, allocations: 0.7869 MB / 0.7755 GB, free: 119.3 MB / 0.5573 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003142/0.9497, allocations: 43.19 kB / 0.7756 GB, free: 119.3 MB / 0.5573 GB
Notification: Performance of setup shared object (initialization): time 0.0001468/0.9498, allocations: 391.1 kB / 0.776 GB, free: 118.9 MB / 0.5573 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003866/0.9537, allocations: 1.807 MB / 0.7777 GB, free: 118.9 MB / 0.5573 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003711/0.9574, allocations: 2.116 MB / 0.7798 GB, free: 118.7 MB / 0.5573 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003686/0.9611, allocations: 1.92 MB / 0.7817 GB, free: 118.6 MB / 0.5573 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.208e-05/0.9612, allocations: 6.281 kB / 0.7817 GB, free: 118.6 MB / 0.5573 GB
Notification: Performance of matching and sorting (n=278) (initialization): time 0.03874/0.9999, allocations: 14.13 MB / 0.7955 GB, free: 118.1 MB / 0.5573 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0004746/1, allocations: 2.738 MB / 0.7982 GB, free: 115.2 MB / 0.5573 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.681e-05/1, allocations: 6.594 kB / 0.7982 GB, free: 115.2 MB / 0.5573 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01925/1.02, allocations: 6.425 MB / 0.8044 GB, free: 114.9 MB / 0.5573 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002001/1.022, allocations: 344.9 kB / 0.8048 GB, free: 114.9 MB / 0.5573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06018/1.082, allocations: 26.43 MB / 0.8306 GB, free: 109.2 MB / 0.5573 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003434/1.085, allocations: 497 kB / 0.831 GB, free: 109.1 MB / 0.5573 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004969/1.086, allocations: 82.88 kB / 0.8311 GB, free: 109 MB / 0.5573 GB
Notification: Performance of prepare postOptimizeDAE: time 1.127e-05/1.086, allocations: 3.562 kB / 0.8311 GB, free: 109 MB / 0.5573 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.03701/1.123, allocations: 13.82 MB / 0.8446 GB, free: 106.7 MB / 0.5573 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.399e-05/1.123, allocations: 3.812 kB / 0.8446 GB, free: 106.7 MB / 0.5573 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01774/1.141, allocations: 6.43 MB / 0.8509 GB, free: 105.7 MB / 0.5573 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001763/1.143, allocations: 320.5 kB / 0.8512 GB, free: 105.7 MB / 0.5573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0536/1.196, allocations: 26.44 MB / 0.877 GB, free: 84.06 MB / 0.5573 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003221/1.199, allocations: 466.6 kB / 0.8775 GB, free: 83.7 MB / 0.5573 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001973/1.2, allocations: 79.36 kB / 0.8776 GB, free: 83.63 MB / 0.5573 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
         Syn2.e2d:VARIABLE(start = Syn2.e2d0 unit = "1" fixed = true )  "d-axis sub-transient voltage" type: Real
         Syn2.e2q:VARIABLE(start = Syn2.e2q0 unit = "1" fixed = true )  "q-axis sub-transient voltage" type: Real
         Syn2.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 (188):
 * Single equations (assignments): 187
 * 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.001114/1.201, allocations: 496.5 kB / 0.878 GB, free: 83.16 MB / 0.5573 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003962/1.201, allocations: 272.6 kB / 0.8783 GB, free: 82.89 MB / 0.5573 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03518/1.236, allocations: 14.3 MB / 0.8923 GB, free: 71.88 MB / 0.5573 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.182e-05/1.236, allocations: 20 kB / 0.8923 GB, free: 71.86 MB / 0.5573 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.284e-05/1.237, allocations: 20 kB / 0.8923 GB, free: 71.84 MB / 0.5573 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.118e-05/1.237, allocations: 11.06 kB / 0.8923 GB, free: 71.84 MB / 0.5573 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.04034/1.277, allocations: 17.82 MB / 0.9097 GB, free: 57.7 MB / 0.5573 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.003e-05/1.277, allocations: 3.516 kB / 0.9097 GB, free: 57.7 MB / 0.5573 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001576/1.279, allocations: 251.6 kB / 0.91 GB, free: 57.45 MB / 0.5573 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01534/1.294, allocations: 6.017 MB / 0.9158 GB, free: 51.48 MB / 0.5573 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0006248/1.295, allocations: 47.98 kB / 0.9159 GB, free: 51.44 MB / 0.5573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04532/1.34, allocations: 22.67 MB / 0.938 GB, free: 28.63 MB / 0.5573 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.116e-06/1.34, allocations: 4 kB / 0.938 GB, free: 28.62 MB / 0.5573 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03995/1.38, allocations: 16.84 MB / 0.9545 GB, free: 11.73 MB / 0.5573 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0009704/1.381, allocations: 388.3 kB / 0.9548 GB, free: 11.33 MB / 0.5573 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004122/1.381, allocations: 43.98 kB / 0.9549 GB, free: 11.29 MB / 0.5573 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002525/1.384, allocations: 363.7 kB / 0.9552 GB, free: 10.93 MB / 0.5573 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001767/1.386, allocations: 0.5448 MB / 0.9558 GB, free: 10.38 MB / 0.5573 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001736/1.386, allocations: 59.98 kB / 0.9558 GB, free: 10.32 MB / 0.5573 GB
Notification: Performance of sorting global known variables: time 0.001462/1.387, allocations: 1.303 MB / 0.9571 GB, free: 9.012 MB / 0.5573 GB
Notification: Performance of sort global known variables: time 1.1e-07/1.387, allocations: 0 / 0.9571 GB, free: 9.012 MB / 0.5573 GB
Notification: Performance of remove unused functions: time 0.00408/1.391, allocations: 1.707 MB / 0.9588 GB, free: 7.312 MB / 0.5573 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 16 (order3_2.delta,order3_2.w,order3_2.e1q,order2.delta,order2.w,Syn2.delta,Syn2.w,Syn2.e1q,Syn2.e2q,Syn2.e2d,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 (148):
 * Single equations (assignments): 147
 * 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 {(63,8.1%) 25}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.0003837/1.392, allocations: 180.8 kB / 0.9589 GB, free: 7.129 MB / 0.5573 GB
Notification: Performance of simCode: created initialization part: time 0.02837/1.42, allocations: 11.33 MB / 0.97 GB, free: 11.79 MB / 0.573 GB
Notification: Performance of simCode: created event and clocks part: time 6.602e-06/1.42, allocations: 0 / 0.97 GB, free: 11.79 MB / 0.573 GB
Notification: Performance of simCode: created simulation system equations: time 0.0129/1.433, allocations: 4.597 MB / 0.9745 GB, free: 7.191 MB / 0.573 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001878/1.435, allocations: 204.3 kB / 0.9747 GB, free: 6.988 MB / 0.573 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.009858/1.445, allocations: 6.267 MB / 0.9808 GB, free: 0.6797 MB / 0.573 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001407/1.446, allocations: 1.794 MB / 0.9825 GB, free: 14.86 MB / 0.5886 GB
Notification: Performance of simCode: alias equations: time 0.001162/1.448, allocations: 216.5 kB / 0.9828 GB, free: 14.65 MB / 0.5886 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000494/1.448, allocations: 115.4 kB / 0.9829 GB, free: 14.54 MB / 0.5886 GB
Notification: Performance of SimCode: time 5.81e-07/1.448, allocations: 4 kB / 0.9829 GB, free: 14.54 MB / 0.5886 GB
Notification: Performance of Templates: time 0.3384/1.786, allocations: 71.29 MB / 1.052 GB, free: 141.6 MB / 0.5886 GB
make -j1 -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII.makefile
(rm -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII.sim & ./OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRIII.pipe 2>&1)