Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR.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.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 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.TwoAreas.Two_Areas_PSSE_AVR,tolerance=0.001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR")
translateModel(OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR,tolerance=0.001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001028/0.001028, allocations: 182.2 kB / 15.98 MB, free: 5.77 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.169/1.169, allocations: 222.9 MB / 239.7 MB, free: 10.98 MB / 202.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0009328/0.0009327, allocations: 106.3 kB / 289.9 MB, free: 10.94 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.4065/0.4065, allocations: 78.52 MB / 418.5 MB, free: 8.477 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.001199/0.001199, allocations: 67.89 kB / 497.5 MB, free: 10.28 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2727/0.2739, allocations: 68.16 MB / 0.5524 GB, free: 19.88 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR): time 0.01275/0.2867, allocations: 20.21 MB / 0.5721 GB, free: 15.59 MB / 394.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01279/0.2995, allocations: 6.489 MB / 0.5785 GB, free: 9.074 MB / 394.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.005041/0.3046, allocations: 43.94 kB / 0.5785 GB, free: 9.031 MB / 394.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.003637/0.3083, allocations: 0.7686 MB / 0.5793 GB, free: 8.258 MB / 394.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.008872/0.3172, allocations: 2.376 MB / 0.5816 GB, free: 5.891 MB / 394.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005158/0.3224, allocations: 2.021 MB / 0.5836 GB, free: 3.895 MB / 394.7 MB
Notification: Performance of NFFlatten.flatten: time 0.009928/0.3323, allocations: 6.852 MB / 0.5903 GB, free: 13.02 MB / 410.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002665/0.335, allocations: 1.218 MB / 0.5914 GB, free: 11.75 MB / 410.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003859/0.3389, allocations: 2.279 MB / 0.5937 GB, free: 9.465 MB / 410.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004572/0.3435, allocations: 3.013 MB / 0.5966 GB, free: 6.438 MB / 410.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.001687/0.3452, allocations: 304 kB / 0.5969 GB, free: 6.141 MB / 410.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002118/0.3473, allocations: 0.5426 MB / 0.5974 GB, free: 5.598 MB / 410.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.001637/0.349, allocations: 1.104 MB / 0.5985 GB, free: 4.488 MB / 410.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.003618/0.3526, allocations: 2.466 MB / 0.6009 GB, free: 2.008 MB / 410.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.00842/0.361, allocations: 7.833 MB / 0.6086 GB, free: 10.15 MB / 426.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.182e-06/0.3611, allocations: 0 / 0.6086 GB, free: 10.15 MB / 426.7 MB
Notification: Performance of FrontEnd: time 1.684e-06/0.3611, allocations: 0 / 0.6086 GB, free: 10.15 MB / 426.7 MB
Notification: Performance of Transformations before backend: time 0.0003093/0.3614, allocations: 0 / 0.6086 GB, free: 10.15 MB / 426.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 691
 * Number of variables: 691
Notification: Performance of Generate backend data structure: time 0.04174/0.4031, allocations: 10.54 MB / 0.6189 GB, free: 15.52 MB / 442.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.867e-05/0.4032, allocations: 11.97 kB / 0.6189 GB, free: 15.51 MB / 442.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01235/0.4156, allocations: 1.941 MB / 0.6208 GB, free: 13.56 MB / 442.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.008409/0.424, allocations: 3.344 MB / 0.624 GB, free: 10.14 MB / 442.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001226/0.4253, allocations: 1.013 MB / 0.625 GB, free: 8.973 MB / 442.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001488/0.4268, allocations: 331.8 kB / 0.6253 GB, free: 8.648 MB / 442.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0116/0.4384, allocations: 3.945 MB / 0.6292 GB, free: 4.629 MB / 442.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 9.119e-05/0.4385, allocations: 0 / 0.6292 GB, free: 4.629 MB / 442.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007626/0.4393, allocations: 112 kB / 0.6293 GB, free: 4.52 MB / 442.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.574e-05/0.4394, allocations: 64 kB / 0.6294 GB, free: 4.457 MB / 442.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.009013/0.4484, allocations: 3.804 MB / 0.6331 GB, free: 0.6406 MB / 442.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.03605/0.4845, allocations: 15.59 MB / 0.6483 GB, free: 464 kB / 458.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0165/0.501, allocations: 6.459 MB / 0.6546 GB, free: 9.758 MB / 474.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006624/0.5077, allocations: 2.44 MB / 0.657 GB, free: 7.297 MB / 474.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.001081/0.5088, allocations: 0.7621 MB / 0.6577 GB, free: 6.367 MB / 474.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.008006/0.5169, allocations: 2.872 MB / 0.6605 GB, free: 3.441 MB / 474.7 MB
Notification: Performance of pre-optimization done (n=331): time 1.205e-05/0.5169, allocations: 0 / 0.6605 GB, free: 3.441 MB / 474.7 MB
Notification: Performance of matching and sorting (n=331): time 0.3436/0.8605, allocations: 25.85 MB / 0.6858 GB, free: 108.6 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.655e-05/0.8606, allocations: 193.9 kB / 0.686 GB, free: 108.4 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007812/0.8684, allocations: 4.528 MB / 0.6904 GB, free: 107.5 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0009377/0.8694, allocations: 101.4 kB / 0.6905 GB, free: 107.4 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001187/0.8706, allocations: 1.753 MB / 0.6922 GB, free: 106.3 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.00146/0.872, allocations: 1.202 MB / 0.6934 GB, free: 105.7 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001824/0.8739, allocations: 1.064 MB / 0.6944 GB, free: 105.2 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 4.733e-05/0.8739, allocations: 301.1 kB / 0.6947 GB, free: 104.9 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005778/0.8797, allocations: 3.018 MB / 0.6976 GB, free: 103.6 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006343/0.8861, allocations: 3.545 MB / 0.7011 GB, free: 101.6 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01105/0.8972, allocations: 6.105 MB / 0.7071 GB, free: 98.27 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.441e-05/0.8972, allocations: 5.844 kB / 0.7071 GB, free: 98.27 MB / 490.7 MB
Notification: Performance of matching and sorting (n=739) (initialization): time 0.01804/0.9152, allocations: 9.394 MB / 0.7162 GB, free: 89.1 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.807e-05/0.9153, allocations: 8 kB / 0.7163 GB, free: 89.09 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.008e-05/0.9154, allocations: 12 kB / 0.7163 GB, free: 89.08 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02208/0.9374, allocations: 10.29 MB / 0.7263 GB, free: 78.79 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004592/0.9421, allocations: 0.9831 MB / 0.7273 GB, free: 77.8 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0146/0.9567, allocations: 6.287 MB / 0.7334 GB, free: 71.49 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00644/0.9632, allocations: 0.7306 MB / 0.7341 GB, free: 70.75 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008346/0.964, allocations: 188 kB / 0.7343 GB, free: 70.57 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 27
 * Number of states: 0 ()
 * Number of discrete variables: 10 ($whenCondition2,$whenCondition1,$whenCondition4,$whenCondition3,$whenCondition6,$whenCondition5,$whenCondition8,$whenCondition7,g4.eSDC1A.rotatingExciterLimited.sISO.local_reset,g2.eSDC1A.rotatingExciterLimited.sISO.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (584):
 * Single equations (assignments): 581
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 2
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(80,72)}
Notification: Performance of prepare postOptimizeDAE: time 0.001558/0.9656, allocations: 0.7742 MB / 0.7351 GB, free: 69.79 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008838/0.9665, allocations: 470 kB / 0.7355 GB, free: 69.32 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0323/0.9988, allocations: 11.61 MB / 0.7468 GB, free: 57.66 MB / 490.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.584e-05/0.9988, allocations: 37.08 kB / 0.7469 GB, free: 57.62 MB / 490.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.358e-06/0.9989, allocations: 0 / 0.7469 GB, free: 57.62 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.724e-05/0.9989, allocations: 17.62 kB / 0.7469 GB, free: 57.61 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 postOpt removeSimpleEquations (simulation): time 0.03991/1.039, allocations: 17.7 MB / 0.7642 GB, free: 39.74 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.82e-05/1.039, allocations: 0 / 0.7642 GB, free: 39.74 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002675/1.042, allocations: 431.5 kB / 0.7646 GB, free: 39.32 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01416/1.056, allocations: 4.757 MB / 0.7692 GB, free: 34.55 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0006979/1.056, allocations: 79.97 kB / 0.7693 GB, free: 34.47 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04369/1.1, allocations: 19.61 MB / 0.7885 GB, free: 14.73 MB / 490.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.575e-06/1.1, allocations: 0 / 0.7885 GB, free: 14.73 MB / 490.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.2968/1.397, allocations: 97.65 MB / 0.8838 GB, free: 12.65 MB / 0.573 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001786/1.399, allocations: 0.6975 MB / 0.8845 GB, free: 11.93 MB / 0.573 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001556/1.4, allocations: 64 kB / 0.8846 GB, free: 11.86 MB / 0.573 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005131/1.406, allocations: 387.5 kB / 0.8849 GB, free: 11.48 MB / 0.573 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00258/1.408, allocations: 0.6041 MB / 0.8855 GB, free: 10.88 MB / 0.573 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0006417/1.409, allocations: 99.97 kB / 0.8856 GB, free: 10.78 MB / 0.573 GB
Notification: Performance of sorting global known variables: time 0.006257/1.415, allocations: 2.992 MB / 0.8886 GB, free: 7.793 MB / 0.573 GB
Notification: Performance of sort global known variables: time 2.11e-07/1.415, allocations: 0 / 0.8886 GB, free: 7.793 MB / 0.573 GB
Notification: Performance of remove unused functions: time 0.008591/1.424, allocations: 2.096 MB / 0.8906 GB, free: 5.703 MB / 0.573 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 38 (g1.g1.w,g1.g1.delta,g1.g1.Epd,g1.g1.Epq,g1.g1.PSIkd,g1.g1.PSIkq,g1.sEXS.simpleLagLim.state,g1.sEXS.leadLag.TF.x_scaled[1],g2.g2.w,g2.g2.delta,g2.g2.Epd,g2.g2.Epq,g2.g2.PSIkd,g2.g2.PSIkq,g2.eSDC1A.imDerivativeLag.x,g2.eSDC1A.imLeadLag.TF.x_scaled[1],g2.eSDC1A.simpleLagLim.state,g2.eSDC1A.rotatingExciterLimited.sISO.y,g2.eSDC1A.TransducerDelay.state,g3.g3.w,g3.g3.delta,g3.g3.Epd,g3.g3.Epq,g3.g3.PSIkd,g3.g3.PSIkq,g3.sEXS.simpleLagLim.state,g3.sEXS.leadLag.TF.x_scaled[1],g4.gENSAL.w,g4.gENSAL.delta,g4.gENSAL.Epd,g4.gENSAL.Epq,g4.gENSAL.PSIkd,g4.gENSAL.PSIkq,g4.eSDC1A.imDerivativeLag.x,g4.eSDC1A.imLeadLag.TF.x_scaled[1],g4.eSDC1A.simpleLagLim.state,g4.eSDC1A.rotatingExciterLimited.sISO.y,g4.eSDC1A.TransducerDelay.state)
 * Number of discrete variables: 8 ($whenCondition7,$whenCondition8,$whenCondition5,$whenCondition6,$whenCondition3,$whenCondition4,$whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (238):
 * Single equations (assignments): 237
 * Array equations: 0
 * Algorithm blocks: 0
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(54,52)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.001035/1.425, allocations: 258.5 kB / 0.8908 GB, free: 5.457 MB / 0.573 GB
Notification: Performance of simCode: created initialization part: time 0.2336/1.659, allocations: 7.847 MB / 0.8985 GB, free: 190.1 MB / 0.573 GB
Notification: Performance of simCode: created event and clocks part: time 6.382e-06/1.659, allocations: 0.9375 kB / 0.8985 GB, free: 190.1 MB / 0.573 GB
Notification: Performance of simCode: created simulation system equations: time 0.01302/1.672, allocations: 4.408 MB / 0.9028 GB, free: 189.7 MB / 0.573 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003102/1.675, allocations: 393.1 kB / 0.9032 GB, free: 189.7 MB / 0.573 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02419/1.699, allocations: 14.38 MB / 0.9172 GB, free: 183.8 MB / 0.573 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001747/1.701, allocations: 2.479 MB / 0.9197 GB, free: 183 MB / 0.573 GB
Notification: Performance of simCode: alias equations: time 0.002242/1.703, allocations: 0.4936 MB / 0.9201 GB, free: 182.9 MB / 0.573 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001295/1.704, allocations: 286.8 kB / 0.9204 GB, free: 182.9 MB / 0.573 GB
Notification: Performance of SimCode: time 5.71e-07/1.704, allocations: 0 / 0.9204 GB, free: 182.9 MB / 0.573 GB
Notification: Performance of Templates: time 0.1405/1.845, allocations: 107 MB / 1.025 GB, free: 86.65 MB / 0.573 GB
make -j1 -f OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR.makefile
(rm -f OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR.pipe ; mkfifo OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR.pipe >> ../files/OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR.sim & ./OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE_AVR.pipe 2>&1)