Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.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_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII")
translateModel(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.0007973/0.0007975, allocations: 175.8 kB / 16.08 MB, free: 5.727 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.259/1.259, allocations: 222.9 MB / 239.7 MB, free: 11.91 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.0008043/0.0008046, allocations: 102.7 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.4269/0.4269, allocations: 78.42 MB / 419.6 MB, free: 9.77 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003432/0.0003432, allocations: 8 kB / 499.1 MB, free: 10.73 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2493/0.2497, allocations: 68.75 MB / 0.5545 GB, free: 20.25 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII): time 0.008091/0.2578, allocations: 13.7 MB / 0.5679 GB, free: 6.496 MB / 378.7 MB
Notification: Performance of NFInst.instExpressions: time 0.005367/0.2632, allocations: 3.41 MB / 0.5712 GB, free: 3.074 MB / 378.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001579/0.2648, allocations: 32 kB / 0.5713 GB, free: 3.043 MB / 378.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0008328/0.2657, allocations: 397.4 kB / 0.5716 GB, free: 2.652 MB / 378.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.0027/0.2684, allocations: 1.142 MB / 0.5728 GB, free: 1.508 MB / 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.002348/0.2707, allocations: 1.356 MB / 0.5741 GB, free: 176 kB / 378.7 MB
Notification: Performance of NFFlatten.flatten: time 0.003128/0.2739, allocations: 3.129 MB / 0.5771 GB, free: 13.03 MB / 394.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008109/0.2747, allocations: 0.6514 MB / 0.5778 GB, free: 12.34 MB / 394.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001399/0.2761, allocations: 1.213 MB / 0.579 GB, free: 11.12 MB / 394.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001484/0.2776, allocations: 1.641 MB / 0.5806 GB, free: 9.473 MB / 394.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0002805/0.2779, allocations: 132 kB / 0.5807 GB, free: 9.344 MB / 394.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0006401/0.2785, allocations: 279.8 kB / 0.5809 GB, free: 9.07 MB / 394.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0004253/0.279, allocations: 0.5517 MB / 0.5815 GB, free: 8.516 MB / 394.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.00104/0.28, allocations: 1.351 MB / 0.5828 GB, free: 7.156 MB / 394.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.002759/0.2828, allocations: 3.383 MB / 0.5861 GB, free: 3.762 MB / 394.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.406e-06/0.2828, allocations: 0 / 0.5861 GB, free: 3.762 MB / 394.7 MB
Notification: Performance of FrontEnd: time 1.253e-06/0.2828, allocations: 0 / 0.5861 GB, free: 3.762 MB / 394.7 MB
Notification: Performance of Transformations before backend: time 3.714e-05/0.2828, allocations: 0 / 0.5861 GB, free: 3.762 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: 487
 * Number of variables: 487
Notification: Performance of Generate backend data structure: time 0.01815/0.301, allocations: 5.515 MB / 0.5915 GB, free: 14.18 MB / 410.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.085e-05/0.301, allocations: 8.031 kB / 0.5915 GB, free: 14.17 MB / 410.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01419/0.3152, allocations: 2.244 MB / 0.5937 GB, free: 11.92 MB / 410.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003011/0.3183, allocations: 1.638 MB / 0.5953 GB, free: 10.21 MB / 410.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006101/0.3189, allocations: 0.6591 MB / 0.5959 GB, free: 9.457 MB / 410.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004156/0.3193, allocations: 203.9 kB / 0.5961 GB, free: 9.258 MB / 410.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005578/0.3249, allocations: 2.898 MB / 0.599 GB, free: 6.34 MB / 410.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.765e-05/0.325, allocations: 0 / 0.599 GB, free: 6.34 MB / 410.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002219/0.3252, allocations: 79.94 kB / 0.599 GB, free: 6.262 MB / 410.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.847e-05/0.3252, allocations: 44 kB / 0.5991 GB, free: 6.219 MB / 410.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00524/0.3305, allocations: 2.666 MB / 0.6017 GB, free: 3.551 MB / 410.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.01601/0.3465, allocations: 9.559 MB / 0.611 GB, free: 9.562 MB / 426.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01023/0.3567, allocations: 4.034 MB / 0.615 GB, free: 5.414 MB / 426.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0036/0.3604, allocations: 1.885 MB / 0.6168 GB, free: 3.523 MB / 426.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002616/0.3606, allocations: 41.95 kB / 0.6168 GB, free: 3.484 MB / 426.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.585e-05/0.3607, allocations: 52.56 kB / 0.6169 GB, free: 3.422 MB / 426.7 MB
Notification: Performance of pre-optimization done (n=228): time 4.228e-06/0.3607, allocations: 4 kB / 0.6169 GB, free: 3.418 MB / 426.7 MB
Notification: Performance of matching and sorting (n=228): time 0.1181/0.4788, allocations: 28.83 MB / 0.645 GB, free: 6.512 MB / 458.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.298e-05/0.4789, allocations: 128.8 kB / 0.6452 GB, free: 6.363 MB / 458.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002482/0.4814, allocations: 1.976 MB / 0.6471 GB, free: 4.367 MB / 458.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0003955/0.4818, allocations: 73.64 kB / 0.6472 GB, free: 4.289 MB / 458.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0007494/0.4826, allocations: 1.069 MB / 0.6482 GB, free: 3.207 MB / 458.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0007662/0.4833, allocations: 0.8049 MB / 0.649 GB, free: 2.391 MB / 458.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003684/0.4837, allocations: 255.8 kB / 0.6492 GB, free: 2.133 MB / 458.7 MB
Notification: Performance of setup shared object (initialization): time 8.629e-05/0.4838, allocations: 305.1 kB / 0.6495 GB, free: 1.832 MB / 458.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003845/0.4877, allocations: 1.864 MB / 0.6514 GB, free: 15.96 MB / 474.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004116/0.4918, allocations: 2.23 MB / 0.6535 GB, free: 13.63 MB / 474.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.05384/0.5456, allocations: 13.71 MB / 0.6669 GB, free: 15.77 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.067e-05/0.5457, allocations: 7.938 kB / 0.6669 GB, free: 15.76 MB / 490.7 MB
Notification: Performance of matching and sorting (n=377) (initialization): time 0.03814/0.5838, allocations: 12.14 MB / 0.6788 GB, free: 3.574 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.000202/0.5841, allocations: 303.5 kB / 0.6791 GB, free: 3.18 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.503e-05/0.5841, allocations: 4 kB / 0.6791 GB, free: 3.176 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.2323/0.8164, allocations: 5.203 MB / 0.6842 GB, free: 106.7 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00248/0.8189, allocations: 490.4 kB / 0.6846 GB, free: 106.7 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04985/0.8688, allocations: 21.33 MB / 0.7055 GB, free: 99.59 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003281/0.8721, allocations: 433 kB / 0.7059 GB, free: 99.54 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002225/0.8723, allocations: 108 kB / 0.706 GB, free: 99.52 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 8.175e-06/0.8724, allocations: 3.938 kB / 0.706 GB, free: 99.52 MB / 490.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.03385/0.9062, allocations: 11.84 MB / 0.7175 GB, free: 94.15 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.59e-05/0.9063, allocations: 8 kB / 0.7175 GB, free: 94.14 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01511/0.9214, allocations: 5.208 MB / 0.7226 GB, free: 89.63 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002104/0.9235, allocations: 478.7 kB / 0.7231 GB, free: 89.17 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04464/0.9682, allocations: 21.32 MB / 0.7439 GB, free: 67.74 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003185/0.9714, allocations: 411.1 kB / 0.7443 GB, free: 67.38 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002647/0.9717, allocations: 107.9 kB / 0.7444 GB, free: 67.27 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: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (290):
 * Single equations (assignments): 289
 * 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): 1 system
   {(62,26,8.3%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.00105/0.9727, allocations: 0.5127 MB / 0.7449 GB, free: 66.76 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003991/0.9731, allocations: 268.6 kB / 0.7452 GB, free: 66.49 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03338/1.007, allocations: 11.43 MB / 0.7563 GB, free: 55.02 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.589e-05/1.007, allocations: 11.95 kB / 0.7563 GB, free: 55.01 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.36e-06/1.007, allocations: 4 kB / 0.7563 GB, free: 55 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006758/1.007, allocations: 369.1 kB / 0.7567 GB, free: 54.62 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005329/1.008, allocations: 40 kB / 0.7567 GB, free: 54.58 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002286/1.01, allocations: 259.7 kB / 0.757 GB, free: 54.33 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003645/1.014, allocations: 1.331 MB / 0.7583 GB, free: 53 MB / 490.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0129/1.027, allocations: 5.173 MB / 0.7633 GB, free: 47.87 MB / 490.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.009495/1.036, allocations: 5.386 MB / 0.7686 GB, free: 42.46 MB / 490.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002801/1.039, allocations: 1.503 MB / 0.7701 GB, free: 40.96 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.00135/1.04, allocations: 1.29 MB / 0.7713 GB, free: 39.66 MB / 490.7 MB
Notification: Performance of Backend: time 5.51e-07/1.04, allocations: 0 / 0.7713 GB, free: 39.66 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.03132/1.072, allocations: 9.131 MB / 0.7802 GB, free: 30.52 MB / 490.7 MB
Notification: Performance of SimCode: time 0.009659/1.081, allocations: 6.631 MB / 0.7867 GB, free: 23.85 MB / 490.7 MB
Notification: Performance of Templates: time 0.0971/1.179, allocations: 74.24 MB / 0.8592 GB, free: 13.62 MB / 0.5417 GB
make -j1 -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.makefile
(rm -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe >> ../files/OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.sim & ./OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Examples.PSATSystems.ThreeArea.FourthOrder_AVRIII.pipe 2>&1)