Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test.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.Tests.Controls.PSAT.AVR.AVRTypeI_Test,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test")
translateModel(OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.004208/0.004209, allocations: 169.5 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.183/1.183, allocations: 223.2 MB / 239 MB, free: 1.781 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.0008254/0.0008254, allocations: 98.14 kB / 290.2 MB, free: 13.94 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4284/0.4284, allocations: 79.43 MB / 420.8 MB, free: 43.72 MB / 330.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003707/0.0003705, allocations: 8 kB / 0.489 GB, free: 2.062 MB / 362.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3078/0.3082, allocations: 69.51 MB / 0.5569 GB, free: 38.62 MB / 426.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 0.1158/0.424, allocations: 101 MB / 0.6556 GB, free: 4.543 MB / 458.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002335/0.4243, allocations: 90.02 kB / 0.6557 GB, free: 4.457 MB / 458.7 MB
Notification: Performance of FrontEnd - DAE generated: time 0.07451/0.4988, allocations: 29.02 MB / 0.684 GB, free: 7.297 MB / 490.7 MB
Notification: Performance of FrontEnd: time 1.503e-06/0.4988, allocations: 0 / 0.684 GB, free: 7.297 MB / 490.7 MB
Notification: Performance of Transformations before backend: time 0.0001006/0.499, allocations: 24 kB / 0.684 GB, free: 7.273 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: 198
 * Number of variables: 198
Notification: Performance of Generate backend data structure: time 0.005892/0.5049, allocations: 2.138 MB / 0.6861 GB, free: 5.07 MB / 490.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.753e-05/0.5049, allocations: 12.03 kB / 0.6861 GB, free: 5.059 MB / 490.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00346/0.5084, allocations: 0.5681 MB / 0.6867 GB, free: 4.488 MB / 490.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002294/0.5107, allocations: 0.8497 MB / 0.6875 GB, free: 3.582 MB / 490.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002735/0.511, allocations: 238.4 kB / 0.6878 GB, free: 3.32 MB / 490.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003264/0.5113, allocations: 80 kB / 0.6878 GB, free: 3.242 MB / 490.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002917/0.5142, allocations: 1.236 MB / 0.689 GB, free: 1.988 MB / 490.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.743e-05/0.5143, allocations: 4 kB / 0.689 GB, free: 1.984 MB / 490.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001136/0.5144, allocations: 31.98 kB / 0.6891 GB, free: 1.953 MB / 490.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.17e-05/0.5144, allocations: 20 kB / 0.6891 GB, free: 1.934 MB / 490.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002467/0.5169, allocations: 1.171 MB / 0.6902 GB, free: 0.7617 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.007504/0.5244, allocations: 3.789 MB / 0.6939 GB, free: 12.83 MB / 0.4948 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002372/0.5268, allocations: 1.586 MB / 0.6955 GB, free: 11.12 MB / 0.4948 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001025/0.527, allocations: 9 kB / 0.6955 GB, free: 11.11 MB / 0.4948 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.252e-05/0.527, allocations: 43.56 kB / 0.6955 GB, free: 11.06 MB / 0.4948 GB
Notification: Performance of pre-optimization done (n=99): time 2.344e-06/0.527, allocations: 4 kB / 0.6955 GB, free: 11.06 MB / 0.4948 GB
Notification: Performance of matching and sorting (n=99): time 0.02723/0.5542, allocations: 8.158 MB / 0.7035 GB, free: 2.855 MB / 0.4948 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.492e-05/0.5544, allocations: 137.5 kB / 0.7036 GB, free: 2.691 MB / 0.4948 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001642/0.556, allocations: 0.8726 MB / 0.7045 GB, free: 1.805 MB / 0.4948 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001374/0.5562, allocations: 45.7 kB / 0.7045 GB, free: 1.754 MB / 0.4948 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004153/0.5566, allocations: 0.5128 MB / 0.705 GB, free: 1.234 MB / 0.4948 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0004054/0.557, allocations: 350.8 kB / 0.7054 GB, free: 0.8867 MB / 0.4948 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 8.324e-05/0.5571, allocations: 28 kB / 0.7054 GB, free: 0.8594 MB / 0.4948 GB
Notification: Performance of setup shared object (initialization): time 0.0001451/0.5573, allocations: 345.9 kB / 0.7057 GB, free: 0.5156 MB / 0.4948 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00148/0.5587, allocations: 0.7086 MB / 0.7064 GB, free: 15.81 MB / 0.5105 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001525/0.5603, allocations: 0.8243 MB / 0.7072 GB, free: 14.95 MB / 0.5105 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001539/0.5618, allocations: 0.8156 MB / 0.708 GB, free: 14.12 MB / 0.5105 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.338e-06/0.5618, allocations: 0 / 0.708 GB, free: 14.12 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=125) (initialization): time 0.01417/0.576, allocations: 5.035 MB / 0.7129 GB, free: 9.055 MB / 0.5105 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003667/0.5764, allocations: 0.945 MB / 0.7139 GB, free: 8.039 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.099e-05/0.5764, allocations: 0 / 0.7139 GB, free: 8.039 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00696/0.5834, allocations: 2.178 MB / 0.716 GB, free: 5.855 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008634/0.5843, allocations: 171.8 kB / 0.7161 GB, free: 5.688 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02275/0.607, allocations: 11.34 MB / 0.7272 GB, free: 10.09 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001604/0.6087, allocations: 195.1 kB / 0.7274 GB, free: 9.895 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001685/0.6089, allocations: 35.98 kB / 0.7274 GB, free: 9.859 MB / 0.5261 GB
Notification: Performance of prepare postOptimizeDAE: time 9.208e-06/0.6089, allocations: 8 kB / 0.7274 GB, free: 9.852 MB / 0.5261 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.01426/0.6231, allocations: 4.879 MB / 0.7322 GB, free: 4.949 MB / 0.5261 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.323e-05/0.6232, allocations: 7.938 kB / 0.7322 GB, free: 4.941 MB / 0.5261 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006978/0.6302, allocations: 2.184 MB / 0.7344 GB, free: 2.75 MB / 0.5261 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008663/0.6311, allocations: 163.8 kB / 0.7345 GB, free: 2.59 MB / 0.5261 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02279/0.6539, allocations: 11.32 MB / 0.7456 GB, free: 7 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001641/0.6555, allocations: 191.1 kB / 0.7457 GB, free: 6.812 MB / 0.5417 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002016/0.6558, allocations: 35.98 kB / 0.7458 GB, free: 6.777 MB / 0.5417 GB
Warning: Assuming fixed start value for the following 2 variables:
         order6Type2_Inputs_Outputs.w:VARIABLE(start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order6Type2_Inputs_Outputs.e2d:VARIABLE(start = order6Type2_Inputs_Outputs.e2d0 unit = "1" fixed = true )  "d-axis sub-transient voltage" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 10
 * 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 (89):
 * Single equations (assignments): 85
 * 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 {24 12}
Notification: Performance of prepare postOptimizeDAE: time 0.0007768/0.6565, allocations: 361.2 kB / 0.7461 GB, free: 6.41 MB / 0.5417 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003838/0.6569, allocations: 122.5 kB / 0.7462 GB, free: 6.289 MB / 0.5417 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01431/0.6713, allocations: 4.965 MB / 0.7511 GB, free: 1.305 MB / 0.5417 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.411e-05/0.6713, allocations: 8 kB / 0.7511 GB, free: 1.297 MB / 0.5417 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.211e-06/0.6713, allocations: 11.95 kB / 0.7511 GB, free: 1.285 MB / 0.5417 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.211e-05/0.6714, allocations: 7.984 kB / 0.7511 GB, free: 1.277 MB / 0.5417 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01666/0.688, allocations: 6.78 MB / 0.7577 GB, free: 10.36 MB / 0.5573 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.235e-05/0.6881, allocations: 0 / 0.7577 GB, free: 10.36 MB / 0.5573 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0008973/0.689, allocations: 127.8 kB / 0.7579 GB, free: 10.24 MB / 0.5573 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.006139/0.6951, allocations: 2.054 MB / 0.7599 GB, free: 8.18 MB / 0.5573 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002132/0.6954, allocations: 23.95 kB / 0.7599 GB, free: 8.156 MB / 0.5573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.2516/0.9469, allocations: 8.652 MB / 0.7683 GB, free: 144.2 MB / 0.5573 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.296e-06/0.947, allocations: 4.812 kB / 0.7683 GB, free: 144.2 MB / 0.5573 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01551/0.9625, allocations: 5.979 MB / 0.7742 GB, free: 144 MB / 0.5573 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004198/0.963, allocations: 217.3 kB / 0.7744 GB, free: 143.9 MB / 0.5573 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001598/0.9631, allocations: 23.47 kB / 0.7744 GB, free: 143.9 MB / 0.5573 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001118/0.9643, allocations: 146.5 kB / 0.7746 GB, free: 143.9 MB / 0.5573 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005508/0.9648, allocations: 196.5 kB / 0.7747 GB, free: 143.9 MB / 0.5573 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.533e-05/0.9649, allocations: 26.22 kB / 0.7748 GB, free: 143.9 MB / 0.5573 GB
Notification: Performance of sorting global known variables: time 0.0009258/0.9658, allocations: 0.6888 MB / 0.7754 GB, free: 143.8 MB / 0.5573 GB
Notification: Performance of sort global known variables: time 1.8e-07/0.9658, allocations: 0 / 0.7754 GB, free: 143.8 MB / 0.5573 GB
Notification: Performance of remove unused functions: time 0.001626/0.9675, allocations: 0.7185 MB / 0.7761 GB, free: 143.8 MB / 0.5573 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 2
 * Number of states: 10 (order6Type2_Inputs_Outputs.delta,order6Type2_Inputs_Outputs.w,order6Type2_Inputs_Outputs.e1q,order6Type2_Inputs_Outputs.e1d,order6Type2_Inputs_Outputs.e2q,order6Type2_Inputs_Outputs.e2d,aVRI.firstOrder.y,aVRI.firstOrder2.y,aVRI.transferFunction.x_scaled[1],aVRI.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 (67):
 * Single equations (assignments): 65
 * 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: 0
 * Non-linear torn systems: 1 {26 10}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0002561/0.9677, allocations: 163 kB / 0.7763 GB, free: 143.7 MB / 0.5573 GB
Notification: Performance of simCode: created initialization part: time 0.01065/0.9784, allocations: 4.844 MB / 0.781 GB, free: 142.8 MB / 0.5573 GB
Notification: Performance of simCode: created event and clocks part: time 6.371e-06/0.9784, allocations: 0 / 0.781 GB, free: 142.8 MB / 0.5573 GB
Notification: Performance of simCode: created simulation system equations: time 0.004289/0.9827, allocations: 1.838 MB / 0.7828 GB, free: 142.5 MB / 0.5573 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0008207/0.9836, allocations: 95.81 kB / 0.7829 GB, free: 142.5 MB / 0.5573 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003527/0.9871, allocations: 2.841 MB / 0.7857 GB, free: 141.2 MB / 0.5573 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005131/0.9876, allocations: 1.162 MB / 0.7868 GB, free: 140.5 MB / 0.5573 GB
Notification: Performance of simCode: alias equations: time 0.0004617/0.9881, allocations: 112.1 kB / 0.7869 GB, free: 140.5 MB / 0.5573 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002136/0.9883, allocations: 59.97 kB / 0.787 GB, free: 140.5 MB / 0.5573 GB
Notification: Performance of SimCode: time 4.41e-07/0.9883, allocations: 0 / 0.787 GB, free: 140.5 MB / 0.5573 GB
Notification: Performance of Templates: time 0.04073/1.029, allocations: 28.8 MB / 0.8151 GB, free: 138.8 MB / 0.5573 GB
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test.makefile
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test.sim & ./OpenIPSL_dev_OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.Controls.PSAT.AVR.AVRTypeI_Test.pipe 2>&1)