Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.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.TwoAreas.Two_Areas_PSSE,tolerance=0.001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE")
translateModel(OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE,tolerance=0.001,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.006944/0.006944, allocations: 98.77 kB / 15.73 MB, free: 6.066 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.477/1.477, allocations: 222.9 MB / 239.4 MB, free: 4.531 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.00178/0.00178, allocations: 180.5 kB / 289.7 MB, free: 2.258 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.4961/0.4961, allocations: 78.53 MB / 418.4 MB, free: 3.09 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001569/0.001569, allocations: 80.14 kB / 0.5536 GB, free: 0.9531 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4203/0.4219, allocations: 68.17 MB / 0.6202 GB, free: 9.805 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE): time 0.01156/0.4335, allocations: 14.96 MB / 0.6348 GB, free: 11.22 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01109/0.4447, allocations: 4.608 MB / 0.6393 GB, free: 6.594 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004102/0.4488, allocations: 28 kB / 0.6393 GB, free: 6.566 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003095/0.4519, allocations: 0.779 MB / 0.6401 GB, free: 5.781 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.009249/0.4613, allocations: 2.421 MB / 0.6424 GB, free: 3.379 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003818/0.4651, allocations: 1.339 MB / 0.6437 GB, free: 2.055 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.008508/0.4736, allocations: 4.895 MB / 0.6485 GB, free: 13.14 MB / 462.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001578/0.4753, allocations: 0.7953 MB / 0.6493 GB, free: 12.31 MB / 462.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003425/0.4787, allocations: 1.883 MB / 0.6511 GB, free: 10.42 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004354/0.4831, allocations: 2.447 MB / 0.6535 GB, free: 7.965 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.00246/0.4856, allocations: 404 kB / 0.6539 GB, free: 7.57 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003804/0.4894, allocations: 0.6287 MB / 0.6545 GB, free: 6.941 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002711/0.4922, allocations: 0.9767 MB / 0.6555 GB, free: 5.961 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.007066/0.4992, allocations: 2.009 MB / 0.6574 GB, free: 3.941 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01287/0.5122, allocations: 5.34 MB / 0.6627 GB, free: 14.58 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 1.197e-05/0.5122, allocations: 8 kB / 0.6627 GB, free: 14.57 MB / 478.1 MB
Notification: Performance of FrontEnd: time 2.635e-06/0.5123, allocations: 0 / 0.6627 GB, free: 14.57 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 0.0002748/0.5125, allocations: 4 kB / 0.6627 GB, free: 14.57 MB / 478.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 493
 * Number of variables: 493
Notification: Performance of Generate backend data structure: time 0.04273/0.5553, allocations: 9.629 MB / 0.6721 GB, free: 4.863 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.872e-05/0.5554, allocations: 16.02 kB / 0.6721 GB, free: 4.848 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01144/0.5668, allocations: 1.765 MB / 0.6738 GB, free: 3.078 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005289/0.5721, allocations: 2.189 MB / 0.676 GB, free: 0.832 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007973/0.573, allocations: 0.6663 MB / 0.6766 GB, free: 76 kB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008831/0.5739, allocations: 244 kB / 0.6768 GB, free: 15.83 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.006015/0.5799, allocations: 2.682 MB / 0.6795 GB, free: 13.14 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.56e-05/0.58, allocations: 3.984 kB / 0.6795 GB, free: 13.14 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002289/0.5802, allocations: 80 kB / 0.6795 GB, free: 13.06 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.746e-05/0.5802, allocations: 51.98 kB / 0.6796 GB, free: 13.01 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006235/0.5865, allocations: 2.83 MB / 0.6823 GB, free: 10.18 MB / 494.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.02197/0.6085, allocations: 11.01 MB / 0.6931 GB, free: 14.62 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01175/0.6203, allocations: 4.979 MB / 0.698 GB, free: 9.434 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00466/0.625, allocations: 1.927 MB / 0.6998 GB, free: 7.508 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003438/0.6254, allocations: 49.84 kB / 0.6999 GB, free: 7.461 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.114e-05/0.6254, allocations: 64.5 kB / 0.7 GB, free: 7.391 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=249): time 4.599e-06/0.6254, allocations: 4 kB / 0.7 GB, free: 7.387 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=249): time 0.1274/0.7528, allocations: 29.57 MB / 0.7288 GB, free: 9.754 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.42e-05/0.753, allocations: 77.41 kB / 0.7289 GB, free: 9.664 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00668/0.7597, allocations: 2.91 MB / 0.7317 GB, free: 6.754 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.001114/0.7608, allocations: 81.7 kB / 0.7318 GB, free: 6.668 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001604/0.7624, allocations: 1.274 MB / 0.7331 GB, free: 5.383 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001863/0.7643, allocations: 0.9451 MB / 0.734 GB, free: 4.43 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003589/0.7679, allocations: 0.7407 MB / 0.7347 GB, free: 3.676 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 0.0001058/0.768, allocations: 309.1 kB / 0.735 GB, free: 3.371 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005709/0.7738, allocations: 2.284 MB / 0.7372 GB, free: 1.078 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006043/0.7798, allocations: 2.681 MB / 0.7399 GB, free: 14.3 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01149/0.7914, allocations: 4.851 MB / 0.7446 GB, free: 9.371 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.507e-05/0.7914, allocations: 7.938 kB / 0.7446 GB, free: 9.363 MB / 0.545 GB
Notification: Performance of matching and sorting (n=531) (initialization): time 0.02145/0.8129, allocations: 7.242 MB / 0.7517 GB, free: 2.109 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 4.008e-05/0.813, allocations: 11.98 kB / 0.7517 GB, free: 2.098 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.061e-05/0.813, allocations: 4 kB / 0.7517 GB, free: 2.094 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01911/0.8321, allocations: 5.726 MB / 0.7573 GB, free: 12.36 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003735/0.8359, allocations: 0.6671 MB / 0.7579 GB, free: 11.69 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01496/0.8509, allocations: 5.447 MB / 0.7633 GB, free: 6.219 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006369/0.8573, allocations: 0.5206 MB / 0.7638 GB, free: 5.695 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001072/0.8584, allocations: 140 kB / 0.7639 GB, free: 5.559 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 23
 * 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 (410):
 * Single equations (assignments): 409
 * 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
   {(62,60)}
Notification: Performance of prepare postOptimizeDAE: time 0.002624/0.8611, allocations: 0.5323 MB / 0.7644 GB, free: 5.016 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001595/0.8627, allocations: 353.7 kB / 0.7648 GB, free: 4.668 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.4256/1.288, allocations: 10.51 MB / 0.775 GB, free: 130.7 MB / 0.5606 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.826e-05/1.288, allocations: 25.06 kB / 0.775 GB, free: 130.7 MB / 0.5606 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.222e-05/1.288, allocations: 0 / 0.775 GB, free: 130.7 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.223e-05/1.289, allocations: 11.52 kB / 0.7751 GB, free: 130.7 MB / 0.5606 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.03813/1.327, allocations: 14.63 MB / 0.7893 GB, free: 126 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.046e-05/1.327, allocations: 0 / 0.7893 GB, free: 126 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001713/1.328, allocations: 256.8 kB / 0.7896 GB, free: 126 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01403/1.343, allocations: 4.762 MB / 0.7942 GB, free: 124 MB / 0.5606 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0007386/1.343, allocations: 61.03 kB / 0.7943 GB, free: 123.9 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04926/1.393, allocations: 21.48 MB / 0.8153 GB, free: 103.2 MB / 0.5606 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.909e-06/1.393, allocations: 4 kB / 0.8153 GB, free: 103.2 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03756/1.43, allocations: 12.83 MB / 0.8278 GB, free: 90.28 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001515/1.432, allocations: 0.5057 MB / 0.8283 GB, free: 89.79 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001757/1.434, allocations: 44 kB / 0.8283 GB, free: 89.74 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005137/1.439, allocations: 248.6 kB / 0.8286 GB, free: 89.5 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002909/1.442, allocations: 0.5218 MB / 0.8291 GB, free: 88.98 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0007524/1.442, allocations: 60 kB / 0.8291 GB, free: 88.92 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.003075/1.446, allocations: 1.881 MB / 0.831 GB, free: 87.05 MB / 0.5606 GB
Notification: Performance of sort global known variables: time 1.5e-07/1.446, allocations: 0 / 0.831 GB, free: 87.05 MB / 0.5606 GB
Notification: Performance of remove unused functions: time 0.004507/1.45, allocations: 1.125 MB / 0.8321 GB, free: 85.95 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 20 (g1.gENSAL.w,g1.gENSAL.delta,g1.gENSAL.Epq,g1.gENSAL.PSIkd,g1.gENSAL.PSIppq,g2.gENSAL.w,g2.gENSAL.delta,g2.gENSAL.Epq,g2.gENSAL.PSIkd,g2.gENSAL.PSIppq,g3.gENSAL.w,g3.gENSAL.delta,g3.gENSAL.Epq,g3.gENSAL.PSIkd,g3.gENSAL.PSIppq,g4.gENSAL.w,g4.gENSAL.delta,g4.gENSAL.Epq,g4.gENSAL.PSIkd,g4.gENSAL.PSIppq)
 * 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 (156):
 * Single equations (assignments): 155
 * 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.0005821/1.451, allocations: 184.2 kB / 0.8323 GB, free: 85.77 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.01627/1.467, allocations: 6.158 MB / 0.8383 GB, free: 79.6 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 5.371e-06/1.467, allocations: 4 kB / 0.8383 GB, free: 79.59 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.0114/1.478, allocations: 3.804 MB / 0.842 GB, free: 75.78 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005/1.483, allocations: 270.3 kB / 0.8423 GB, free: 75.52 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01425/1.498, allocations: 6.384 MB / 0.8485 GB, free: 69.13 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001161/1.499, allocations: 1.845 MB / 0.8503 GB, free: 67.26 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.001735/1.501, allocations: 288.9 kB / 0.8506 GB, free: 66.97 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008638/1.502, allocations: 195.4 kB / 0.8507 GB, free: 66.78 MB / 0.5606 GB
Notification: Performance of SimCode: time 1.463e-06/1.502, allocations: 0 / 0.8507 GB, free: 66.78 MB / 0.5606 GB
Notification: Performance of Templates: time 0.09314/1.595, allocations: 61.83 MB / 0.9111 GB, free: 4.957 MB / 0.5606 GB
make -j1 -f OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.makefile
(rm -f OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe ; mkfifo OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe >> ../files/OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.sim & ./OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Examples.TwoAreas.Two_Areas_PSSE.pipe 2>&1)