Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.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.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 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.TwoArea.FifthOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII")
translateModel(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001051/0.001051, allocations: 113.5 kB / 16.42 MB, free: 6.453 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.329/1.329, allocations: 222.9 MB / 240.1 MB, free: 4.559 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.00128/0.00128, allocations: 188.4 kB / 290.4 MB, free: 2.262 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4998/0.4998, allocations: 91.9 MB / 432.4 MB, free: 9.227 MB / 302.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001499/0.001499, allocations: 79.03 kB / 0.5735 GB, free: 33.85 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1063/0.1078, allocations: 71.29 MB / 0.6431 GB, free: 3.512 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII): time 0.01823/0.1261, allocations: 21.93 MB / 0.6645 GB, free: 13.5 MB / 478.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01077/0.1369, allocations: 4.888 MB / 0.6693 GB, free: 8.586 MB / 478.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003545/0.1405, allocations: 44 kB / 0.6694 GB, free: 8.543 MB / 478.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002955/0.1435, allocations: 0.8789 MB / 0.6702 GB, free: 7.656 MB / 478.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.007134/0.1506, allocations: 2.303 MB / 0.6725 GB, free: 5.344 MB / 478.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005458/0.1561, allocations: 2.071 MB / 0.6745 GB, free: 3.305 MB / 478.1 MB
Notification: Performance of NFFlatten.flatten: time 0.007468/0.1636, allocations: 5.771 MB / 0.6801 GB, free: 13.51 MB / 494.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002298/0.1659, allocations: 1.321 MB / 0.6814 GB, free: 12.14 MB / 494.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003319/0.1692, allocations: 2.345 MB / 0.6837 GB, free: 9.781 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003914/0.1732, allocations: 2.971 MB / 0.6866 GB, free: 6.797 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001495/0.1747, allocations: 444 kB / 0.687 GB, free: 6.363 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001995/0.1767, allocations: 0.5859 MB / 0.6876 GB, free: 5.777 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001486/0.1782, allocations: 1.163 MB / 0.6887 GB, free: 4.609 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003509/0.1817, allocations: 2.625 MB / 0.6913 GB, free: 1.969 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.007473/0.1892, allocations: 6.258 MB / 0.6974 GB, free: 11.68 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 5.04e-06/0.1892, allocations: 0 / 0.6974 GB, free: 11.68 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 1.553e-06/0.1892, allocations: 4 kB / 0.6974 GB, free: 11.68 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 0.0001788/0.1894, allocations: 0 / 0.6974 GB, free: 11.68 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 894
 * Number of variables: 894
Notification: Performance of Generate backend data structure: time 0.03089/0.2203, allocations: 9.217 MB / 0.7064 GB, free: 2.371 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 4.239e-05/0.2204, allocations: 8.031 kB / 0.7064 GB, free: 2.363 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02803/0.2484, allocations: 4.016 MB / 0.7103 GB, free: 14.34 MB / 0.5137 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005625/0.2541, allocations: 2.678 MB / 0.713 GB, free: 11.58 MB / 0.5137 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0009556/0.255, allocations: 0.9534 MB / 0.7139 GB, free: 10.49 MB / 0.5137 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001031/0.2561, allocations: 352 kB / 0.7142 GB, free: 10.14 MB / 0.5137 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.009891/0.266, allocations: 4.743 MB / 0.7189 GB, free: 5.371 MB / 0.5137 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.471e-05/0.2661, allocations: 7.875 kB / 0.7189 GB, free: 5.363 MB / 0.5137 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004174/0.2665, allocations: 139.9 kB / 0.719 GB, free: 5.227 MB / 0.5137 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.664e-05/0.2666, allocations: 84 kB / 0.7191 GB, free: 5.145 MB / 0.5137 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008919/0.2755, allocations: 4.256 MB / 0.7232 GB, free: 0.8828 MB / 0.5137 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.03244/0.3079, allocations: 18.25 MB / 0.7411 GB, free: 13.45 MB / 0.545 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01293/0.3209, allocations: 5.016 MB / 0.746 GB, free: 8.348 MB / 0.545 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005937/0.3268, allocations: 2.794 MB / 0.7487 GB, free: 5.543 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004621/0.3273, allocations: 74.83 kB / 0.7488 GB, free: 5.473 MB / 0.545 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.007398/0.3347, allocations: 3.181 MB / 0.7519 GB, free: 2.25 MB / 0.545 GB
Notification: Performance of pre-optimization done (n=384): time 6.392e-06/0.3348, allocations: 0 / 0.7519 GB, free: 2.25 MB / 0.545 GB
Notification: Performance of matching and sorting (n=384): time 0.561/0.8957, allocations: 67.76 MB / 0.818 GB, free: 83.09 MB / 0.5606 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.67e-05/0.8958, allocations: 144.9 kB / 0.8182 GB, free: 82.93 MB / 0.5606 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003524/0.8994, allocations: 3.247 MB / 0.8213 GB, free: 80.54 MB / 0.5606 GB
Notification: Performance of collectPreVariables (initialization): time 0.000692/0.9001, allocations: 99.23 kB / 0.8214 GB, free: 80.49 MB / 0.5606 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009163/0.901, allocations: 1.978 MB / 0.8234 GB, free: 79.19 MB / 0.5606 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001058/0.9021, allocations: 1.313 MB / 0.8247 GB, free: 77.88 MB / 0.5606 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001191/0.9033, allocations: 0.6783 MB / 0.8253 GB, free: 77.19 MB / 0.5606 GB
Notification: Performance of setup shared object (initialization): time 3.251e-05/0.9033, allocations: 309.1 kB / 0.8256 GB, free: 76.89 MB / 0.5606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004944/0.9082, allocations: 2.601 MB / 0.8281 GB, free: 74.28 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005529/0.9138, allocations: 3.233 MB / 0.8313 GB, free: 70.82 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.06906/0.9829, allocations: 19 MB / 0.8499 GB, free: 51.57 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.986e-05/0.9829, allocations: 4 kB / 0.8499 GB, free: 51.57 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=542) (initialization): time 0.0561/1.039, allocations: 17.3 MB / 0.8668 GB, free: 34.22 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 5.414e-05/1.039, allocations: 63.41 kB / 0.8668 GB, free: 34.15 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.486e-05/1.039, allocations: 12 kB / 0.8668 GB, free: 34.14 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02493/1.064, allocations: 7.157 MB / 0.8738 GB, free: 26.97 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003497/1.068, allocations: 0.6865 MB / 0.8745 GB, free: 26.29 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08233/1.15, allocations: 33.3 MB / 0.907 GB, free: 8.809 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005825/1.156, allocations: 0.6172 MB / 0.9076 GB, free: 8.188 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004989/1.156, allocations: 156 kB / 0.9078 GB, free: 8.035 MB / 0.5762 GB
Warning: Assuming fixed start value for the following 5 variables:
         order5.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3.delta:VARIABLE(flow=false start = order3.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order3.e1q:VARIABLE(start = order3.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
         order5.delta:VARIABLE(flow=false start = order5.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 65
 * Number of states: 0 ()
 * Number of discrete variables: 2 ($whenCondition2,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (407):
 * Single equations (assignments): 404
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(92,44,5.8%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.001727/1.158, allocations: 0.666 MB / 0.9084 GB, free: 7.355 MB / 0.5762 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008628/1.159, allocations: 458.3 kB / 0.9089 GB, free: 6.902 MB / 0.5762 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.05788/1.217, allocations: 16.87 MB / 0.9253 GB, free: 5.957 MB / 0.5919 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.818e-05/1.217, allocations: 19.92 kB / 0.9253 GB, free: 5.938 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.116e-06/1.217, allocations: 0 / 0.9253 GB, free: 5.938 MB / 0.5919 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001327/1.218, allocations: 0.5648 MB / 0.9259 GB, free: 5.348 MB / 0.5919 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001342/1.22, allocations: 64 kB / 0.926 GB, free: 5.285 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004894/1.225, allocations: 381.6 kB / 0.9263 GB, free: 4.914 MB / 0.5919 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.006877/1.231, allocations: 1.906 MB / 0.9282 GB, free: 3.004 MB / 0.5919 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02779/1.259, allocations: 8.361 MB / 0.9364 GB, free: 10.68 MB / 0.6075 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01878/1.278, allocations: 8.234 MB / 0.9444 GB, free: 2.359 MB / 0.6075 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.005489/1.284, allocations: 2.284 MB / 0.9466 GB, free: 68 kB / 0.6075 GB
Notification: Performance of sorting global known variables: time 0.0031/1.287, allocations: 2.139 MB / 0.9487 GB, free: 13.93 MB / 0.6231 GB
Notification: Performance of Backend: time 6.12e-07/1.287, allocations: 4 kB / 0.9487 GB, free: 13.92 MB / 0.6231 GB
Notification: Performance of simCode: created initialization part: time 0.02263/1.309, allocations: 6.888 MB / 0.9554 GB, free: 7.012 MB / 0.6231 GB
Notification: Performance of SimCode: time 0.01231/1.322, allocations: 9.008 MB / 0.9642 GB, free: 13.97 MB / 0.6387 GB
Notification: Performance of Templates: time 0.361/1.683, allocations: 84.64 MB / 1.047 GB, free: 240.5 MB / 0.7012 GB
make -j1 -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.makefile
(rm -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.sim & ./OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.pipe 2>&1)