Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII.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.0.1/package.mo", uses=false)
Using package OpenIPSL with version 3.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/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.FifthOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.000891/0.000891, allocations: 100.4 kB / 15.05 MB, free: 1.105 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.245/1.245, allocations: 223.2 MB / 238.9 MB, free: 5.465 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.001181/0.001181, allocations: 185 kB / 290.2 MB, free: 2.148 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.433/0.433, allocations: 78.51 MB / 419.8 MB, free: 4.328 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003427/0.0003427, allocations: 12 kB / 499.7 MB, free: 6.379 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1149/0.1153, allocations: 69.36 MB / 0.5557 GB, free: 0.9766 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII): time 0.196/0.3113, allocations: 12.19 MB / 0.5676 GB, free: 36.15 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.006/0.3173, allocations: 3.595 MB / 0.5711 GB, free: 33.56 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001656/0.319, allocations: 32.34 kB / 0.5711 GB, free: 33.56 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0009899/0.32, allocations: 399.3 kB / 0.5715 GB, free: 33.39 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002991/0.323, allocations: 1.342 MB / 0.5728 GB, free: 32.47 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002516/0.3255, allocations: 1.281 MB / 0.5741 GB, free: 31.88 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003257/0.3288, allocations: 2.396 MB / 0.5764 GB, free: 30.58 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009076/0.3297, allocations: 0.701 MB / 0.5771 GB, free: 30.28 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.00153/0.3312, allocations: 1.331 MB / 0.5784 GB, free: 29.66 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001669/0.3329, allocations: 1.697 MB / 0.5801 GB, free: 28.9 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003996/0.3334, allocations: 142 kB / 0.5802 GB, free: 28.9 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0006634/0.334, allocations: 260.6 kB / 0.5804 GB, free: 28.87 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.000356/0.3344, allocations: 0.5914 MB / 0.581 GB, free: 28.53 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0004257/0.3348, allocations: 0.5239 MB / 0.5815 GB, free: 28.2 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.002004/0.3368, allocations: 2.855 MB / 0.5843 GB, free: 27.32 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 2.234e-06/0.3368, allocations: 0 / 0.5843 GB, free: 27.32 MB / 430.1 MB
Notification: Performance of FrontEnd: time 1.964e-06/0.3369, allocations: 2.688 kB / 0.5843 GB, free: 27.32 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 4.54e-05/0.3369, allocations: 0 / 0.5843 GB, free: 27.32 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 524
 * Number of variables: 524
Notification: Performance of Generate backend data structure: time 0.01938/0.3563, allocations: 6.221 MB / 0.5904 GB, free: 25.05 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.433e-05/0.3563, allocations: 9.188 kB / 0.5904 GB, free: 25.05 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01427/0.3706, allocations: 2.301 MB / 0.5926 GB, free: 24.62 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00327/0.3739, allocations: 1.794 MB / 0.5944 GB, free: 23.56 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005576/0.3745, allocations: 0.7532 MB / 0.5951 GB, free: 22.91 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004096/0.3749, allocations: 219.2 kB / 0.5953 GB, free: 22.83 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005949/0.3808, allocations: 3.211 MB / 0.5985 GB, free: 21.65 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.717e-05/0.3809, allocations: 2.828 kB / 0.5985 GB, free: 21.65 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000236/0.3811, allocations: 81.8 kB / 0.5986 GB, free: 21.59 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.971e-05/0.3812, allocations: 49.5 kB / 0.5986 GB, free: 21.59 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005588/0.3868, allocations: 2.981 MB / 0.6015 GB, free: 20.62 MB / 430.1 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.01695/0.4037, allocations: 10.49 MB / 0.6118 GB, free: 14.7 MB / 430.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01098/0.4148, allocations: 4.876 MB / 0.6165 GB, free: 12.9 MB / 430.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002567/0.415, allocations: 46.19 kB / 0.6166 GB, free: 12.89 MB / 430.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004443/0.4195, allocations: 2.431 MB / 0.6189 GB, free: 11.57 MB / 430.1 MB
Notification: Performance of pre-optimization done (n=249): time 4.749e-06/0.4195, allocations: 1.375 kB / 0.6189 GB, free: 11.57 MB / 430.1 MB
Notification: Performance of matching and sorting (n=249): time 0.1161/0.5356, allocations: 33.47 MB / 0.6516 GB, free: 11.25 MB / 462.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.761e-05/0.5358, allocations: 184.3 kB / 0.6518 GB, free: 11.03 MB / 462.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003543/0.5393, allocations: 2.29 MB / 0.654 GB, free: 8.723 MB / 462.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0006115/0.5399, allocations: 73.7 kB / 0.6541 GB, free: 8.645 MB / 462.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0008421/0.5408, allocations: 1.176 MB / 0.6553 GB, free: 7.457 MB / 462.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001054/0.5418, allocations: 0.8781 MB / 0.6561 GB, free: 6.566 MB / 462.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002411/0.5421, allocations: 64 kB / 0.6562 GB, free: 6.504 MB / 462.1 MB
Notification: Performance of setup shared object (initialization): time 0.0002094/0.5423, allocations: 428.9 kB / 0.6566 GB, free: 6.078 MB / 462.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004454/0.5468, allocations: 2.162 MB / 0.6587 GB, free: 3.914 MB / 462.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004694/0.5515, allocations: 2.521 MB / 0.6612 GB, free: 1.305 MB / 462.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004845/0.5563, allocations: 2.423 MB / 0.6635 GB, free: 14.84 MB / 478.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.059e-05/0.5564, allocations: 8 kB / 0.6635 GB, free: 14.83 MB / 478.1 MB
Notification: Performance of matching and sorting (n=421) (initialization): time 0.04349/0.5999, allocations: 15.21 MB / 0.6784 GB, free: 15.55 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 9.171e-05/0.6, allocations: 110.8 kB / 0.6785 GB, free: 15.41 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.925e-05/0.6, allocations: 12 kB / 0.6785 GB, free: 15.4 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01966/0.6197, allocations: 6.43 MB / 0.6848 GB, free: 8.992 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003138/0.6229, allocations: 0.5891 MB / 0.6854 GB, free: 8.402 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0629/0.6858, allocations: 26.49 MB / 0.7112 GB, free: 13.79 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004464/0.6903, allocations: 0.5909 MB / 0.7118 GB, free: 13.19 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003831/0.6907, allocations: 119.9 kB / 0.7119 GB, free: 13.07 MB / 0.5137 GB
Warning: Assuming fixed start value for the following 7 variables:
         Exc2.firstOrder2.y:VARIABLE(flow=false start = Exc2.firstOrder2.y_start fixed = true )  "Connector of Real output signal" type: Real
         Exc1.firstOrder2.y:VARIABLE(flow=false start = Exc1.firstOrder2.y_start fixed = true )  "Connector of Real output signal" 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(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3_2.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: 4 ($whenCondition3,$whenCondition4,$whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (331):
 * Single equations (assignments): 326
 * Array equations: 0
 * Algorithm blocks: 4
 * 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.001214/0.6919, allocations: 0.5384 MB / 0.7124 GB, free: 12.52 MB / 0.5137 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005781/0.6925, allocations: 292.3 kB / 0.7127 GB, free: 12.23 MB / 0.5137 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04177/0.7343, allocations: 14.76 MB / 0.7271 GB, free: 13.37 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.61e-05/0.7343, allocations: 24 kB / 0.7272 GB, free: 13.35 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.895e-05/0.7344, allocations: 23.95 kB / 0.7272 GB, free: 13.32 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.964e-05/0.7344, allocations: 11.95 kB / 0.7272 GB, free: 13.31 MB / 0.5294 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.04663/0.7811, allocations: 18.48 MB / 0.7452 GB, free: 10.6 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.775e-05/0.7811, allocations: 1.125 kB / 0.7452 GB, free: 10.6 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001524/0.7827, allocations: 251.6 kB / 0.7455 GB, free: 10.35 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01845/0.8011, allocations: 6.289 MB / 0.7516 GB, free: 4.047 MB / 0.545 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0005241/0.8017, allocations: 51.98 kB / 0.7517 GB, free: 3.996 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.2538/1.056, allocations: 26.85 MB / 0.7779 GB, free: 162.9 MB / 0.5606 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.856e-06/1.056, allocations: 0 / 0.7779 GB, free: 162.9 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.04572/1.101, allocations: 17.37 MB / 0.7949 GB, free: 162.3 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001024/1.102, allocations: 421.4 kB / 0.7953 GB, free: 162.1 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0008391/1.103, allocations: 45.56 kB / 0.7953 GB, free: 162.1 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003301/1.107, allocations: 410.3 kB / 0.7957 GB, free: 162.1 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00197/1.109, allocations: 0.5787 MB / 0.7963 GB, free: 162.1 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002654/1.109, allocations: 72.17 kB / 0.7963 GB, free: 162.1 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.002326/1.111, allocations: 1.506 MB / 0.7978 GB, free: 161.9 MB / 0.5606 GB
Notification: Performance of sort global known variables: time 3.41e-07/1.111, allocations: 0 / 0.7978 GB, free: 161.9 MB / 0.5606 GB
Notification: Performance of remove unused functions: time 0.003699/1.115, allocations: 1.186 MB / 0.799 GB, free: 161.9 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 18 (order3_2.delta,order3_2.w,order3_2.e1q,order2.delta,order2.w,Syn2.delta,Syn2.w,Syn2.e1q,Syn2.e2q,Syn2.e2d,Exc1.ExcitationSystem.x_scaled[1],Exc1.derivativeBlock.x,Exc1.firstOrder2.y,Exc1.simpleLagLim.state,Exc2.ExcitationSystem.x_scaled[1],Exc2.derivativeBlock.x,Exc2.firstOrder2.y,Exc2.simpleLagLim.state)
 * Number of discrete variables: 4 ($whenCondition3,$whenCondition4,$whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (158):
 * Single equations (assignments): 153
 * 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: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(67,7.5%) 21}
 * Non-linear torn systems: 2 {1 6,1 6}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0004506/1.115, allocations: 179.3 kB / 0.7991 GB, free: 161.8 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.01911/1.134, allocations: 7.53 MB / 0.8065 GB, free: 160.8 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 6.392e-06/1.134, allocations: 0 / 0.8065 GB, free: 160.8 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.0133/1.148, allocations: 5.122 MB / 0.8115 GB, free: 159 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00183/1.15, allocations: 220.2 kB / 0.8117 GB, free: 158.9 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.008876/1.159, allocations: 6.577 MB / 0.8181 GB, free: 154.9 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001224/1.16, allocations: 1.877 MB / 0.82 GB, free: 154.1 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.001113/1.161, allocations: 267.5 kB / 0.8202 GB, free: 154.1 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000529/1.161, allocations: 161.7 kB / 0.8204 GB, free: 154.1 MB / 0.5606 GB
Notification: Performance of SimCode: time 9.12e-07/1.161, allocations: 0 / 0.8204 GB, free: 154.1 MB / 0.5606 GB
Notification: Performance of Templates: time 0.06608/1.228, allocations: 56.93 MB / 0.876 GB, free: 104.4 MB / 0.5606 GB
make -j1 -f OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII.makefile
Environment - environmentSimulation:
(rm -f OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII.pipe ; mkfifo OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII.pipe >> ../files/OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII.sim & ./OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRII.pipe 2>&1)
Environment - environmentSimulation: