Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_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.ThreeArea.FourthOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0008616/0.000862, allocations: 175.6 kB / 16.08 MB, free: 5.723 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.229/1.229, allocations: 222.9 MB / 239.7 MB, free: 10.2 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.0007226/0.0007226, allocations: 94.14 kB / 290.5 MB, free: 11.02 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.3979/0.3979, allocations: 78.43 MB / 419.6 MB, free: 9.754 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003353/0.0003353, allocations: 16.22 kB / 499.1 MB, free: 10.69 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2427/0.2431, allocations: 68.76 MB / 0.5545 GB, free: 20.22 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII): time 0.008748/0.2519, allocations: 14.46 MB / 0.5686 GB, free: 5.695 MB / 378.7 MB
Notification: Performance of NFInst.instExpressions: time 0.005484/0.2574, allocations: 3.608 MB / 0.5722 GB, free: 2.078 MB / 378.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001319/0.2587, allocations: 32 kB / 0.5722 GB, free: 2.047 MB / 378.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0009378/0.2597, allocations: 441.1 kB / 0.5726 GB, free: 1.613 MB / 378.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.002609/0.2623, allocations: 1.229 MB / 0.5738 GB, free: 392 kB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/Electrical/Machines/PSAT/Order4.mo:25:3-30:9:writable] Warning: In relation order4_1.xq <> order4_1.x1q, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeClassSections: time 0.002733/0.265, allocations: 1.41 MB / 0.5752 GB, free: 14.99 MB / 394.7 MB
Notification: Performance of NFFlatten.flatten: time 0.003613/0.2687, allocations: 3.522 MB / 0.5786 GB, free: 11.46 MB / 394.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009709/0.2696, allocations: 0.7962 MB / 0.5794 GB, free: 10.61 MB / 394.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001509/0.2712, allocations: 1.333 MB / 0.5807 GB, free: 9.27 MB / 394.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001699/0.2729, allocations: 1.769 MB / 0.5824 GB, free: 7.492 MB / 394.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003002/0.2732, allocations: 140 kB / 0.5826 GB, free: 7.355 MB / 394.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0006295/0.2738, allocations: 271.9 kB / 0.5828 GB, free: 7.09 MB / 394.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0004616/0.2743, allocations: 0.61 MB / 0.5834 GB, free: 6.477 MB / 394.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001123/0.2754, allocations: 1.463 MB / 0.5849 GB, free: 5.004 MB / 394.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.002907/0.2783, allocations: 3.715 MB / 0.5885 GB, free: 1.273 MB / 394.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.587e-06/0.2784, allocations: 0 / 0.5885 GB, free: 1.273 MB / 394.7 MB
Notification: Performance of FrontEnd: time 1.132e-06/0.2784, allocations: 4 kB / 0.5885 GB, free: 1.27 MB / 394.7 MB
Notification: Performance of Transformations before backend: time 4.01e-05/0.2784, allocations: 0 / 0.5885 GB, free: 1.27 MB / 394.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 523
 * Number of variables: 523
Notification: Performance of Generate backend data structure: time 0.01899/0.2974, allocations: 5.929 MB / 0.5943 GB, free: 11.24 MB / 410.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.998e-05/0.2975, allocations: 15.97 kB / 0.5943 GB, free: 11.23 MB / 410.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01399/0.3115, allocations: 2.263 MB / 0.5965 GB, free: 8.957 MB / 410.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003835/0.3153, allocations: 1.878 MB / 0.5983 GB, free: 7.012 MB / 410.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006749/0.316, allocations: 0.7252 MB / 0.599 GB, free: 6.172 MB / 410.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004104/0.3164, allocations: 215.9 kB / 0.5993 GB, free: 5.961 MB / 410.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005572/0.322, allocations: 3.035 MB / 0.6022 GB, free: 2.848 MB / 410.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.944e-05/0.3221, allocations: 0 / 0.6022 GB, free: 2.848 MB / 410.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002314/0.3223, allocations: 87.94 kB / 0.6023 GB, free: 2.762 MB / 410.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.968e-05/0.3223, allocations: 52 kB / 0.6024 GB, free: 2.711 MB / 410.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00538/0.3277, allocations: 2.78 MB / 0.6051 GB, free: 15.91 MB / 426.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.01719/0.3449, allocations: 9.891 MB / 0.6147 GB, free: 5.602 MB / 426.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01027/0.3552, allocations: 4.182 MB / 0.6188 GB, free: 1.289 MB / 426.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004109/0.3594, allocations: 1.971 MB / 0.6207 GB, free: 15.31 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003087/0.3597, allocations: 41.95 kB / 0.6208 GB, free: 15.27 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004311/0.364, allocations: 2.228 MB / 0.623 GB, free: 13.02 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=248): time 4.518e-06/0.3641, allocations: 0 / 0.623 GB, free: 13.02 MB / 442.7 MB
Notification: Performance of matching and sorting (n=248): time 0.1063/0.4704, allocations: 27.3 MB / 0.6496 GB, free: 1.59 MB / 458.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001106/0.4705, allocations: 180.2 kB / 0.6498 GB, free: 1.375 MB / 458.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003273/0.4738, allocations: 2.273 MB / 0.652 GB, free: 15.08 MB / 474.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0005943/0.4744, allocations: 81.75 kB / 0.6521 GB, free: 15 MB / 474.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.000864/0.4753, allocations: 1.156 MB / 0.6532 GB, free: 13.83 MB / 474.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001034/0.4763, allocations: 0.8704 MB / 0.6541 GB, free: 12.95 MB / 474.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004134/0.4767, allocations: 288.5 kB / 0.6543 GB, free: 12.66 MB / 474.7 MB
Notification: Performance of setup shared object (initialization): time 8.894e-05/0.4768, allocations: 301.1 kB / 0.6546 GB, free: 12.36 MB / 474.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004103/0.481, allocations: 1.955 MB / 0.6565 GB, free: 10.4 MB / 474.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004379/0.4853, allocations: 2.33 MB / 0.6588 GB, free: 7.98 MB / 474.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.05221/0.5376, allocations: 14.04 MB / 0.6725 GB, free: 9.707 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.302e-05/0.5376, allocations: 4 kB / 0.6725 GB, free: 9.703 MB / 490.7 MB
Notification: Performance of matching and sorting (n=417) (initialization): time 0.2603/0.7979, allocations: 12.5 MB / 0.6847 GB, free: 106.3 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 7.01e-05/0.798, allocations: 129 kB / 0.6849 GB, free: 106.1 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.771e-05/0.7981, allocations: 3.875 kB / 0.6849 GB, free: 106.1 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0173/0.8154, allocations: 5.287 MB / 0.69 GB, free: 106 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003028/0.8185, allocations: 0.5732 MB / 0.6906 GB, free: 106 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04964/0.8681, allocations: 21.3 MB / 0.7114 GB, free: 98.29 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004298/0.8724, allocations: 492.3 kB / 0.7119 GB, free: 98.23 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005672/0.873, allocations: 125.7 kB / 0.712 GB, free: 98.2 MB / 490.7 MB
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
         order4_1.delta:VARIABLE(flow=false start = order4_1.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order4_1.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: 4 ($whenCondition2,$whenCondition1,$whenCondition4,$whenCondition3)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (330):
 * Single equations (assignments): 325
 * 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.001605/0.8747, allocations: 0.5408 MB / 0.7125 GB, free: 97.78 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0007521/0.8754, allocations: 296.7 kB / 0.7128 GB, free: 97.69 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03634/0.9118, allocations: 11.9 MB / 0.7244 GB, free: 87.14 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.969e-05/0.9119, allocations: 11.95 kB / 0.7244 GB, free: 87.13 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.963e-05/0.9119, allocations: 0 / 0.7244 GB, free: 87.13 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008036/0.9127, allocations: 412.7 kB / 0.7248 GB, free: 86.74 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0008461/0.9136, allocations: 44 kB / 0.7248 GB, free: 86.7 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003089/0.9167, allocations: 295.6 kB / 0.7251 GB, free: 86.41 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.004447/0.9212, allocations: 1.378 MB / 0.7265 GB, free: 85.03 MB / 490.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01533/0.9365, allocations: 5.894 MB / 0.7322 GB, free: 79.14 MB / 490.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.01028/0.9468, allocations: 5.891 MB / 0.738 GB, free: 73.16 MB / 490.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003034/0.9499, allocations: 1.612 MB / 0.7396 GB, free: 71.55 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.002157/0.9521, allocations: 1.507 MB / 0.741 GB, free: 70.04 MB / 490.7 MB
Notification: Performance of Backend: time 8.22e-07/0.9521, allocations: 0 / 0.741 GB, free: 70.04 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.01331/0.9654, allocations: 5.09 MB / 0.746 GB, free: 64.91 MB / 490.7 MB
Notification: Performance of SimCode: time 0.007342/0.9728, allocations: 7.346 MB / 0.7532 GB, free: 57.52 MB / 490.7 MB
Notification: Performance of Templates: time 0.06438/1.037, allocations: 52.01 MB / 0.804 GB, free: 5.547 MB / 490.7 MB
make -j1 -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII.makefile
(rm -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII.pipe >> ../files/OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII.sim & ./OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRII.pipe 2>&1)