Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.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_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=40000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=40000,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001094/0.001094, allocations: 109.5 kB / 16.41 MB, free: 5.996 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.315/1.315, allocations: 222.9 MB / 240.1 MB, free: 4.207 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.001334/0.001334, allocations: 192.3 kB / 290.4 MB, free: 1.941 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.4517/0.4517, allocations: 78.54 MB / 419.1 MB, free: 3.066 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.002424/0.002424, allocations: 76.2 kB / 0.5544 GB, free: 0.8594 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3556/0.3581, allocations: 68.17 MB / 0.6209 GB, free: 9.691 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI): time 0.009929/0.368, allocations: 13.57 MB / 0.6342 GB, free: 12.7 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.006942/0.375, allocations: 3.515 MB / 0.6376 GB, free: 9.176 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001821/0.3769, allocations: 28 kB / 0.6376 GB, free: 9.148 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001584/0.3785, allocations: 0.5693 MB / 0.6382 GB, free: 8.574 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004418/0.3829, allocations: 1.56 MB / 0.6397 GB, free: 7.008 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003559/0.3865, allocations: 1.555 MB / 0.6412 GB, free: 5.473 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004363/0.3909, allocations: 3.714 MB / 0.6449 GB, free: 1.742 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001326/0.3922, allocations: 0.8306 MB / 0.6457 GB, free: 0.8711 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001927/0.3941, allocations: 1.509 MB / 0.6471 GB, free: 15.36 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002396/0.3965, allocations: 1.968 MB / 0.6491 GB, free: 13.38 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0007062/0.3973, allocations: 292 kB / 0.6493 GB, free: 13.09 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001071/0.3984, allocations: 431.8 kB / 0.6498 GB, free: 12.67 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0007022/0.3991, allocations: 0.7741 MB / 0.6505 GB, free: 11.89 MB / 462.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.00144/0.4005, allocations: 1.631 MB / 0.6521 GB, free: 10.25 MB / 462.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.003725/0.4042, allocations: 4.004 MB / 0.656 GB, free: 6.234 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.318e-06/0.4043, allocations: 0 / 0.656 GB, free: 6.234 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.162e-06/0.4043, allocations: 0 / 0.656 GB, free: 6.234 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 4.594e-05/0.4043, allocations: 0 / 0.656 GB, free: 6.234 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 518
 * Number of variables: 518
Notification: Performance of Generate backend data structure: time 0.02231/0.4266, allocations: 6.705 MB / 0.6626 GB, free: 15.43 MB / 478.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.221e-05/0.4267, allocations: 8.031 kB / 0.6626 GB, free: 15.42 MB / 478.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0151/0.4418, allocations: 2.296 MB / 0.6648 GB, free: 13.12 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003474/0.4453, allocations: 1.916 MB / 0.6667 GB, free: 11.14 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006258/0.4459, allocations: 0.6788 MB / 0.6674 GB, free: 10.36 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000524/0.4465, allocations: 212 kB / 0.6676 GB, free: 10.15 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.006159/0.4526, allocations: 3.056 MB / 0.6705 GB, free: 7.012 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.602e-05/0.4527, allocations: 3.938 kB / 0.6705 GB, free: 7.008 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002444/0.4529, allocations: 83.94 kB / 0.6706 GB, free: 6.926 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.903e-05/0.453, allocations: 55.94 kB / 0.6707 GB, free: 6.871 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005987/0.459, allocations: 2.819 MB / 0.6734 GB, free: 4.035 MB / 478.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.01761/0.4766, allocations: 9.955 MB / 0.6831 GB, free: 9.637 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01055/0.4872, allocations: 4.111 MB / 0.6872 GB, free: 5.398 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003969/0.4912, allocations: 1.987 MB / 0.6891 GB, free: 3.402 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003016/0.4915, allocations: 43.12 kB / 0.6891 GB, free: 3.359 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.101e-05/0.4915, allocations: 60.56 kB / 0.6892 GB, free: 3.293 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=243): time 4.759e-06/0.4915, allocations: 0 / 0.6892 GB, free: 3.293 MB / 494.1 MB
Notification: Performance of matching and sorting (n=243): time 0.1195/0.611, allocations: 28.19 MB / 0.7167 GB, free: 7.02 MB / 0.5137 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001213/0.6112, allocations: 235.6 kB / 0.717 GB, free: 6.738 MB / 0.5137 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003686/0.6149, allocations: 2.305 MB / 0.7192 GB, free: 4.414 MB / 0.5137 GB
Notification: Performance of collectPreVariables (initialization): time 0.000527/0.6154, allocations: 69.7 kB / 0.7193 GB, free: 4.34 MB / 0.5137 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009501/0.6164, allocations: 1.201 MB / 0.7204 GB, free: 3.129 MB / 0.5137 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00124/0.6176, allocations: 0.8946 MB / 0.7213 GB, free: 2.227 MB / 0.5137 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00148/0.6191, allocations: 0.542 MB / 0.7219 GB, free: 1.676 MB / 0.5137 GB
Notification: Performance of setup shared object (initialization): time 0.0001016/0.6192, allocations: 301.1 kB / 0.7221 GB, free: 1.379 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004468/0.6237, allocations: 1.968 MB / 0.7241 GB, free: 15.4 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004673/0.6284, allocations: 2.338 MB / 0.7263 GB, free: 12.96 MB / 0.5294 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.05637/0.6848, allocations: 14.2 MB / 0.7402 GB, free: 14.53 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.335e-05/0.6848, allocations: 4 kB / 0.7402 GB, free: 14.53 MB / 0.545 GB
Notification: Performance of matching and sorting (n=401) (initialization): time 0.04212/0.727, allocations: 12.5 MB / 0.7524 GB, free: 1.953 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 8.989e-05/0.7271, allocations: 122.7 kB / 0.7525 GB, free: 1.809 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.025e-05/0.7271, allocations: 8 kB / 0.7525 GB, free: 1.801 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01797/0.7451, allocations: 5.249 MB / 0.7577 GB, free: 12.55 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003135/0.7483, allocations: 0.5461 MB / 0.7582 GB, free: 12 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.31/1.058, allocations: 23.18 MB / 0.7808 GB, free: 121.2 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003981/1.062, allocations: 480.5 kB / 0.7813 GB, free: 121.1 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003801/1.063, allocations: 116.1 kB / 0.7814 GB, free: 121.1 MB / 0.5606 GB
Warning: Assuming fixed start value for the following 7 variables:
         order3_2.delta:VARIABLE(flow=false start = order3_2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order3_2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3_2.e1q:VARIABLE(start = order3_2.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         Syn2.delta:VARIABLE(flow=false start = Syn2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         Syn2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.00459/1.067, allocations: 2.299 MB / 0.7837 GB, free: 120.2 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003939/1.071, allocations: 2.305 MB / 0.7859 GB, free: 119.1 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.05108/1.122, allocations: 14.13 MB / 0.7997 GB, free: 115.3 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.175e-05/1.122, allocations: 2.031 kB / 0.7997 GB, free: 115.3 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=401) (initialization_lambda0): time 0.03726/1.16, allocations: 12.46 MB / 0.8119 GB, free: 107.2 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 6.025e-05/1.16, allocations: 121.1 kB / 0.812 GB, free: 107.1 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.747e-05/1.16, allocations: 4 kB / 0.812 GB, free: 107.1 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01581/1.176, allocations: 5.25 MB / 0.8171 GB, free: 101.8 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002811/1.178, allocations: 0.5395 MB / 0.8177 GB, free: 101.3 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05142/1.23, allocations: 23.21 MB / 0.8403 GB, free: 77.97 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003936/1.234, allocations: 441.2 kB / 0.8407 GB, free: 77.59 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003963/1.234, allocations: 116 kB / 0.8408 GB, free: 77.47 MB / 0.5606 GB
Warning: Assuming fixed start value for the following 7 variables:
         order3_2.delta:VARIABLE(flow=false start = order3_2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order3_2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3_2.e1q:VARIABLE(start = order3_2.e1q0 unit = "1" fixed = true )  "q-axis transient voltage" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order2.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         Syn2.delta:VARIABLE(flow=false start = Syn2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         Syn2.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: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (314):
 * Single equations (assignments): 309
 * Array equations: 0
 * Algorithm blocks: 4
 * 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
   {(63,25,8.1%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0007305/1.235, allocations: 403.1 kB / 0.8412 GB, free: 77.07 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006539/1.236, allocations: 310 kB / 0.8415 GB, free: 76.76 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03434/1.27, allocations: 11.71 MB / 0.853 GB, free: 65 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.645e-05/1.27, allocations: 11.95 kB / 0.853 GB, free: 64.99 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.391e-06/1.27, allocations: 0 / 0.853 GB, free: 64.99 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007551/1.271, allocations: 421.9 kB / 0.8534 GB, free: 64.59 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0006388/1.272, allocations: 47.84 kB / 0.8534 GB, free: 64.55 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00271/1.274, allocations: 293.2 kB / 0.8537 GB, free: 64.27 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003786/1.278, allocations: 1.379 MB / 0.855 GB, free: 62.89 MB / 0.5606 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01444/1.292, allocations: 5.748 MB / 0.8607 GB, free: 57.15 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01087/1.303, allocations: 5.966 MB / 0.8665 GB, free: 51.15 MB / 0.5606 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003376/1.307, allocations: 1.628 MB / 0.8681 GB, free: 49.52 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.002109/1.309, allocations: 1.523 MB / 0.8696 GB, free: 47.99 MB / 0.5606 GB
Notification: Performance of Backend: time 6.21e-07/1.309, allocations: 0 / 0.8696 GB, free: 47.99 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.02635/1.335, allocations: 9.873 MB / 0.8792 GB, free: 38.02 MB / 0.5606 GB
Notification: Performance of SimCode: time 0.006595/1.342, allocations: 6.412 MB / 0.8855 GB, free: 31.56 MB / 0.5606 GB
Notification: Performance of Templates: time 0.1184/1.46, allocations: 87.26 MB / 0.9707 GB, free: 8.324 MB / 0.6231 GB
make -j1 -f OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.makefile
(rm -f OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.pipe ; mkfifo OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.pipe >> ../files/OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.sim & ./OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Examples.PSATSystems.ThreeArea.FifthOrder_AVRI.pipe 2>&1)