Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.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.ThreeArea.SixthOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001381/0.001381, allocations: 110.7 kB / 16.42 MB, free: 6.492 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.372/1.372, allocations: 222.9 MB / 240.1 MB, free: 4.555 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.001304/0.001303, allocations: 188.5 kB / 290.4 MB, free: 2.273 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5073/0.5073, allocations: 91.9 MB / 432.4 MB, free: 9.168 MB / 302.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.002548/0.002548, allocations: 81.25 kB / 0.5735 GB, free: 33.7 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1226/0.1251, allocations: 71.27 MB / 0.6431 GB, free: 3.566 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII): time 0.01231/0.1375, allocations: 13.08 MB / 0.6558 GB, free: 6.43 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.0072/0.1447, allocations: 3.324 MB / 0.6591 GB, free: 3.094 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002022/0.1468, allocations: 31.94 kB / 0.6591 GB, free: 3.062 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001566/0.1484, allocations: 0.5072 MB / 0.6596 GB, free: 2.551 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.004491/0.1529, allocations: 1.474 MB / 0.661 GB, free: 1.078 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003492/0.1564, allocations: 1.478 MB / 0.6625 GB, free: 15.62 MB / 478.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004236/0.1606, allocations: 3.383 MB / 0.6658 GB, free: 12.22 MB / 478.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001118/0.1618, allocations: 0.7471 MB / 0.6665 GB, free: 11.44 MB / 478.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001816/0.1636, allocations: 1.4 MB / 0.6679 GB, free: 10.03 MB / 478.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002215/0.1658, allocations: 1.836 MB / 0.6697 GB, free: 8.188 MB / 478.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0006169/0.1664, allocations: 260 kB / 0.6699 GB, free: 7.934 MB / 478.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0009626/0.1674, allocations: 407.8 kB / 0.6703 GB, free: 7.535 MB / 478.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0006441/0.1681, allocations: 0.7039 MB / 0.671 GB, free: 6.828 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.00157/0.1696, allocations: 1.522 MB / 0.6725 GB, free: 5.297 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.003641/0.1733, allocations: 3.678 MB / 0.6761 GB, free: 1.605 MB / 478.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.308e-06/0.1733, allocations: 0 / 0.6761 GB, free: 1.605 MB / 478.1 MB
Notification: Performance of FrontEnd: time 1.253e-06/0.1733, allocations: 0 / 0.6761 GB, free: 1.605 MB / 478.1 MB
Notification: Performance of Transformations before backend: time 8.694e-05/0.1734, allocations: 0 / 0.6761 GB, free: 1.605 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: 489
 * Number of variables: 489
Notification: Performance of Generate backend data structure: time 0.0221/0.1955, allocations: 6.208 MB / 0.6821 GB, free: 11.33 MB / 494.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.823e-05/0.1956, allocations: 8.031 kB / 0.6822 GB, free: 11.32 MB / 494.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01541/0.211, allocations: 2.255 MB / 0.6844 GB, free: 9.059 MB / 494.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002993/0.214, allocations: 1.685 MB / 0.686 GB, free: 7.305 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006333/0.2146, allocations: 0.6669 MB / 0.6867 GB, free: 6.539 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004328/0.2151, allocations: 200 kB / 0.6868 GB, free: 6.344 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.006229/0.2213, allocations: 2.91 MB / 0.6897 GB, free: 3.414 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.029e-05/0.2214, allocations: 0 / 0.6897 GB, free: 3.414 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000251/0.2216, allocations: 79.94 kB / 0.6898 GB, free: 3.336 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.476e-05/0.2217, allocations: 52 kB / 0.6898 GB, free: 3.285 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005787/0.2275, allocations: 2.678 MB / 0.6924 GB, free: 0.6055 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.01716/0.2446, allocations: 9.603 MB / 0.7018 GB, free: 6.582 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01129/0.256, allocations: 4.066 MB / 0.7058 GB, free: 2.398 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004093/0.2601, allocations: 1.896 MB / 0.7076 GB, free: 0.4961 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002396/0.2603, allocations: 41.97 kB / 0.7077 GB, free: 468 kB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.28e-05/0.2604, allocations: 56.56 kB / 0.7077 GB, free: 400 kB / 0.4981 GB
Notification: Performance of pre-optimization done (n=230): time 4.157e-06/0.2604, allocations: 0 / 0.7077 GB, free: 400 kB / 0.4981 GB
Notification: Performance of matching and sorting (n=230): time 0.1185/0.3789, allocations: 26.22 MB / 0.7333 GB, free: 6.105 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.36e-05/0.379, allocations: 128.8 kB / 0.7334 GB, free: 5.957 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003061/0.3821, allocations: 2.033 MB / 0.7354 GB, free: 3.906 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005698/0.3827, allocations: 65.7 kB / 0.7355 GB, free: 3.836 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000874/0.3836, allocations: 1.093 MB / 0.7366 GB, free: 2.73 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001151/0.3847, allocations: 0.8087 MB / 0.7374 GB, free: 1.91 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001495/0.3862, allocations: 0.5142 MB / 0.7379 GB, free: 1.387 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 0.0001003/0.3864, allocations: 305.1 kB / 0.7381 GB, free: 1.086 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004761/0.3911, allocations: 1.916 MB / 0.74 GB, free: 15.16 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005048/0.3962, allocations: 2.266 MB / 0.7422 GB, free: 12.79 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.06216/0.4584, allocations: 14.03 MB / 0.7559 GB, free: 14.61 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.395e-05/0.4584, allocations: 8 kB / 0.7559 GB, free: 14.61 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=385) (initialization): time 0.04411/0.5025, allocations: 12.13 MB / 0.7678 GB, free: 2.43 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 2.827e-05/0.5026, allocations: 8 kB / 0.7678 GB, free: 2.422 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.917e-05/0.5026, allocations: 8 kB / 0.7678 GB, free: 2.414 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.2818/0.7845, allocations: 5.41 MB / 0.7731 GB, free: 106 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002674/0.7872, allocations: 0.4886 MB / 0.7736 GB, free: 106 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.06207/0.8493, allocations: 23.64 MB / 0.7966 GB, free: 99.57 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003873/0.8532, allocations: 436.3 kB / 0.7971 GB, free: 99.51 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003504/0.8535, allocations: 110.4 kB / 0.7972 GB, free: 99.49 MB / 0.5606 GB
Warning: Assuming fixed start value for the following 6 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.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.004492/0.858, allocations: 2.212 MB / 0.7993 GB, free: 98.59 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003919/0.862, allocations: 2.237 MB / 0.8015 GB, free: 97.59 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.05683/0.9188, allocations: 13.98 MB / 0.8152 GB, free: 89.37 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.059e-05/0.9189, allocations: 5.328 kB / 0.8152 GB, free: 89.37 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=385) (initialization_lambda0): time 0.03941/0.9583, allocations: 12.09 MB / 0.827 GB, free: 77.45 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 1.893e-05/0.9583, allocations: 9.641 kB / 0.827 GB, free: 77.44 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.611e-05/0.9583, allocations: 4 kB / 0.827 GB, free: 77.43 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01786/0.9762, allocations: 5.403 MB / 0.8323 GB, free: 72.03 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00244/0.9787, allocations: 495.3 kB / 0.8327 GB, free: 71.54 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05766/1.036, allocations: 23.65 MB / 0.8558 GB, free: 47.77 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003819/1.04, allocations: 410.3 kB / 0.8562 GB, free: 47.41 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000293/1.041, allocations: 107.9 kB / 0.8563 GB, free: 47.31 MB / 0.5606 GB
Warning: Assuming fixed start value for the following 6 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.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 (296):
 * Single equations (assignments): 295
 * 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
   {(63,27)}
Notification: Performance of prepare postOptimizeDAE: time 0.0006626/1.041, allocations: 330.9 kB / 0.8566 GB, free: 46.98 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004941/1.042, allocations: 276.6 kB / 0.8569 GB, free: 46.71 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03826/1.08, allocations: 11.49 MB / 0.8681 GB, free: 35.18 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.836e-05/1.08, allocations: 11.95 kB / 0.8681 GB, free: 35.17 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.911e-06/1.08, allocations: 0 / 0.8681 GB, free: 35.17 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006774/1.081, allocations: 377.1 kB / 0.8685 GB, free: 34.78 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0006357/1.081, allocations: 39.94 kB / 0.8685 GB, free: 34.74 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002776/1.084, allocations: 264.6 kB / 0.8688 GB, free: 34.48 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003812/1.088, allocations: 1.375 MB / 0.8701 GB, free: 33.11 MB / 0.5606 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01533/1.103, allocations: 5.258 MB / 0.8753 GB, free: 27.9 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01124/1.115, allocations: 5.457 MB / 0.8806 GB, free: 22.42 MB / 0.5606 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003352/1.118, allocations: 1.522 MB / 0.8821 GB, free: 20.9 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.001645/1.12, allocations: 1.33 MB / 0.8834 GB, free: 19.56 MB / 0.5606 GB
Notification: Performance of Backend: time 4.11e-07/1.12, allocations: 0 / 0.8834 GB, free: 19.56 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.0293/1.149, allocations: 9.467 MB / 0.8926 GB, free: 10.08 MB / 0.5606 GB
Notification: Performance of SimCode: time 0.006191/1.155, allocations: 5.846 MB / 0.8983 GB, free: 4.195 MB / 0.5606 GB
Notification: Performance of Templates: time 0.1238/1.279, allocations: 88.25 MB / 0.9845 GB, free: 11.99 MB / 0.6544 GB
make -j1 -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.makefile
(rm -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.sim & ./OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.ThreeArea.SixthOrder_AVRIII.pipe 2>&1)