Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII.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.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 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.PSATSystems.TwoArea.ThirdOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII")
translateModel(OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001326/0.001326, allocations: 177.1 kB / 16.08 MB, free: 5.605 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.27/1.27, allocations: 222.8 MB / 239.7 MB, free: 11.89 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.0007215/0.0007215, allocations: 93.8 kB / 290.5 MB, free: 11.22 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4152/0.4152, allocations: 78.42 MB / 419.6 MB, free: 9.836 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003335/0.0003336, allocations: 8 kB / 499.1 MB, free: 10.73 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2466/0.247, allocations: 68.74 MB / 0.5545 GB, free: 20.24 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII): time 0.01403/0.261, allocations: 23.23 MB / 0.5772 GB, free: 12.91 MB / 394.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01023/0.2713, allocations: 5.11 MB / 0.5822 GB, free: 7.781 MB / 394.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003515/0.2748, allocations: 55.94 kB / 0.5822 GB, free: 7.727 MB / 394.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.002032/0.2769, allocations: 0.6635 MB / 0.5829 GB, free: 7.059 MB / 394.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr16.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr67.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr78.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr89.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr49.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr528.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr2829.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr2930.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr3031.m <> 1.0, <> on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Branches/PSAT/TwoWindingTransformer.mo:44:3-44:83:writable] Warning: In relation Tr231.m <> 1.0, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeBindings: time 0.005876/0.2828, allocations: 1.731 MB / 0.5846 GB, free: 5.32 MB / 394.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005494/0.2883, allocations: 1.917 MB / 0.5865 GB, free: 3.434 MB / 394.7 MB
Notification: Performance of NFFlatten.flatten: time 0.009926/0.2983, allocations: 5.412 MB / 0.5917 GB, free: 14 MB / 410.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002055/0.3004, allocations: 1.138 MB / 0.5929 GB, free: 12.81 MB / 410.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003179/0.3036, allocations: 2.048 MB / 0.5949 GB, free: 10.75 MB / 410.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00476/0.3083, allocations: 2.678 MB / 0.5975 GB, free: 8.059 MB / 410.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.001144/0.3095, allocations: 236 kB / 0.5977 GB, free: 7.828 MB / 410.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001321/0.3108, allocations: 371.8 kB / 0.598 GB, free: 7.465 MB / 410.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0007788/0.3116, allocations: 0.9324 MB / 0.599 GB, free: 6.527 MB / 410.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.002315/0.314, allocations: 2.36 MB / 0.6013 GB, free: 4.152 MB / 410.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.005412/0.3194, allocations: 5.834 MB / 0.607 GB, free: 14.3 MB / 426.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.369e-06/0.3194, allocations: 0 / 0.607 GB, free: 14.3 MB / 426.7 MB
Notification: Performance of FrontEnd: time 1.694e-06/0.3194, allocations: 4 kB / 0.607 GB, free: 14.29 MB / 426.7 MB
Notification: Performance of Transformations before backend: time 0.0001625/0.3196, allocations: 0 / 0.607 GB, free: 14.29 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: 892
 * Number of variables: 892
Notification: Performance of Generate backend data structure: time 0.0263/0.3459, allocations: 8.123 MB / 0.6149 GB, free: 6.078 MB / 426.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.215e-05/0.346, allocations: 8.031 kB / 0.6149 GB, free: 6.07 MB / 426.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02675/0.3727, allocations: 4.001 MB / 0.6188 GB, free: 2.059 MB / 426.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005904/0.3787, allocations: 2.654 MB / 0.6214 GB, free: 15.32 MB / 442.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000963/0.3797, allocations: 0.961 MB / 0.6223 GB, free: 14.22 MB / 442.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001134/0.3808, allocations: 344 kB / 0.6227 GB, free: 13.89 MB / 442.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01144/0.3923, allocations: 4.731 MB / 0.6273 GB, free: 9.125 MB / 442.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 9.099e-05/0.3924, allocations: 3.938 kB / 0.6273 GB, free: 9.121 MB / 442.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007269/0.3931, allocations: 140 kB / 0.6274 GB, free: 8.984 MB / 442.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.484e-05/0.3932, allocations: 84 kB / 0.6275 GB, free: 8.902 MB / 442.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01005/0.4033, allocations: 4.24 MB / 0.6316 GB, free: 4.656 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.03756/0.4409, allocations: 18.31 MB / 0.6495 GB, free: 1.16 MB / 458.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01281/0.4537, allocations: 4.99 MB / 0.6544 GB, free: 12.09 MB / 474.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006128/0.4599, allocations: 2.78 MB / 0.6571 GB, free: 9.297 MB / 474.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006514/0.4606, allocations: 74.84 kB / 0.6572 GB, free: 9.223 MB / 474.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.00736/0.4679, allocations: 3.167 MB / 0.6603 GB, free: 6.016 MB / 474.7 MB
Notification: Performance of pre-optimization done (n=382): time 7.585e-06/0.468, allocations: 0 / 0.6603 GB, free: 6.016 MB / 474.7 MB
Notification: Performance of matching and sorting (n=382): time 0.5084/0.9764, allocations: 68.2 MB / 0.7269 GB, free: 83.35 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.9e-05/0.9765, allocations: 145.8 kB / 0.727 GB, free: 83.18 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00362/0.9801, allocations: 3.214 MB / 0.7302 GB, free: 79.98 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0007864/0.9809, allocations: 89.7 kB / 0.7302 GB, free: 79.89 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0009814/0.9819, allocations: 1.968 MB / 0.7322 GB, free: 78.23 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001486/0.9834, allocations: 1.315 MB / 0.7335 GB, free: 76.93 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004612/0.9839, allocations: 342.7 kB / 0.7338 GB, free: 76.58 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 3.819e-05/0.9839, allocations: 305.1 kB / 0.7341 GB, free: 76.28 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004558/0.9885, allocations: 2.581 MB / 0.7366 GB, free: 73.69 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00491/0.9934, allocations: 3.205 MB / 0.7397 GB, free: 70.27 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.06073/1.054, allocations: 18.76 MB / 0.758 GB, free: 51.25 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.928e-05/1.054, allocations: 6.078 kB / 0.758 GB, free: 51.25 MB / 490.7 MB
Notification: Performance of matching and sorting (n=534) (initialization): time 0.05348/1.108, allocations: 16.93 MB / 0.7746 GB, free: 34.27 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 6.592e-05/1.108, allocations: 63.41 kB / 0.7746 GB, free: 34.2 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.974e-05/1.108, allocations: 16 kB / 0.7747 GB, free: 34.18 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0237/1.132, allocations: 7.225 MB / 0.7817 GB, free: 26.95 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003061/1.135, allocations: 0.6669 MB / 0.7824 GB, free: 26.28 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.07616/1.211, allocations: 31.13 MB / 0.8128 GB, free: 11.01 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005383/1.216, allocations: 0.5939 MB / 0.8133 GB, free: 10.41 MB / 0.4948 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007575/1.217, allocations: 156 kB / 0.8135 GB, free: 10.26 MB / 0.4948 GB
Warning: Assuming fixed start value for the following 4 variables:
         order3_1.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
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 (398):
 * Single equations (assignments): 395
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(92,45)}
Notification: Performance of prepare postOptimizeDAE: time 0.002183/1.219, allocations: 0.6659 MB / 0.8141 GB, free: 9.578 MB / 0.4948 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001203/1.22, allocations: 462.2 kB / 0.8146 GB, free: 9.121 MB / 0.4948 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0568/1.277, allocations: 16.83 MB / 0.831 GB, free: 8.219 MB / 0.5105 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001347/1.277, allocations: 27.8 kB / 0.831 GB, free: 8.191 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.038e-05/1.277, allocations: 4 kB / 0.831 GB, free: 8.188 MB / 0.5105 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001567/1.279, allocations: 0.5532 MB / 0.8316 GB, free: 7.609 MB / 0.5105 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001448/1.281, allocations: 64 kB / 0.8316 GB, free: 7.547 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004578/1.285, allocations: 363.6 kB / 0.832 GB, free: 7.191 MB / 0.5105 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.007263/1.292, allocations: 1.843 MB / 0.8338 GB, free: 5.344 MB / 0.5105 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02804/1.32, allocations: 8.304 MB / 0.8419 GB, free: 13.07 MB / 0.5261 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.01987/1.34, allocations: 8.179 MB / 0.8499 GB, free: 4.816 MB / 0.5261 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00583/1.346, allocations: 2.28 MB / 0.8521 GB, free: 2.527 MB / 0.5261 GB
Notification: Performance of sorting global known variables: time 0.004078/1.35, allocations: 2.102 MB / 0.8542 GB, free: 432 kB / 0.5261 GB
Notification: Performance of Backend: time 9.32e-07/1.35, allocations: 0 / 0.8542 GB, free: 432 kB / 0.5261 GB
Notification: Performance of simCode: created initialization part: time 0.02355/1.374, allocations: 6.821 MB / 0.8608 GB, free: 9.57 MB / 0.5417 GB
Notification: Performance of SimCode: time 0.0154/1.389, allocations: 10.65 MB / 0.8712 GB, free: 14.85 MB / 0.5573 GB
Notification: Performance of Templates: time 0.3174/1.707, allocations: 77.58 MB / 0.947 GB, free: 166.4 MB / 0.573 GB
make -j1 -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII.makefile
(rm -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII.pipe >> ../files/OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII.sim & ./OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoArea.ThirdOrder_AVRII.pipe 2>&1)