Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI.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.TwoAreaOld.FourthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI")
translateModel(OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001432/0.001432, 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.269/1.269, allocations: 222.8 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.0008097/0.0008097, allocations: 108.1 kB / 290.5 MB, free: 11.01 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4086/0.4086, allocations: 78.42 MB / 419.6 MB, free: 9.77 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003511/0.000351, allocations: 16.38 kB / 499.1 MB, free: 10.68 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2558/0.2562, allocations: 68.75 MB / 0.5545 GB, free: 20.22 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI): time 0.004793/0.261, allocations: 6.728 MB / 0.5611 GB, free: 13.47 MB / 378.7 MB
Notification: Performance of NFInst.instExpressions: time 0.002648/0.2637, allocations: 2.033 MB / 0.5631 GB, free: 11.43 MB / 378.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0003655/0.264, allocations: 8 kB / 0.5631 GB, free: 11.42 MB / 378.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0004775/0.2645, allocations: 190.8 kB / 0.5633 GB, free: 11.23 MB / 378.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.001296/0.2658, allocations: 0.7128 MB / 0.564 GB, free: 10.52 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.xq <> order4.x1q, <> on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeClassSections: time 0.0009949/0.2669, allocations: 0.611 MB / 0.5646 GB, free: 9.918 MB / 378.7 MB
Notification: Performance of NFFlatten.flatten: time 0.0008179/0.2677, allocations: 1.471 MB / 0.566 GB, free: 8.438 MB / 378.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002893/0.268, allocations: 273.2 kB / 0.5663 GB, free: 8.16 MB / 378.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.000399/0.2684, allocations: 0.5598 MB / 0.5668 GB, free: 7.598 MB / 378.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0005051/0.2689, allocations: 0.7348 MB / 0.5675 GB, free: 6.859 MB / 378.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001137/0.269, allocations: 60 kB / 0.5676 GB, free: 6.801 MB / 378.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0003108/0.2693, allocations: 183.9 kB / 0.5678 GB, free: 6.621 MB / 378.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001646/0.2695, allocations: 262.7 kB / 0.568 GB, free: 6.363 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003113/0.2699, allocations: 0.5938 MB / 0.5686 GB, free: 5.766 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.0008239/0.2707, allocations: 1.539 MB / 0.5701 GB, free: 4.223 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.126e-06/0.2707, allocations: 0 / 0.5701 GB, free: 4.223 MB / 378.7 MB
Notification: Performance of FrontEnd: time 1.202e-06/0.2707, allocations: 0 / 0.5701 GB, free: 4.223 MB / 378.7 MB
Notification: Performance of Transformations before backend: time 1.123e-05/0.2707, allocations: 4 kB / 0.5701 GB, free: 4.219 MB / 378.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 187
 * Number of variables: 187
Notification: Performance of Generate backend data structure: time 0.006676/0.2774, allocations: 2.412 MB / 0.5724 GB, free: 1.742 MB / 378.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.217e-05/0.2774, allocations: 12.02 kB / 0.5725 GB, free: 1.73 MB / 378.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00468/0.2821, allocations: 0.7671 MB / 0.5732 GB, free: 0.9609 MB / 378.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001064/0.2832, allocations: 0.89 MB / 0.5741 GB, free: 16 kB / 378.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002435/0.2835, allocations: 226.6 kB / 0.5743 GB, free: 15.76 MB / 394.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001493/0.2836, allocations: 84 kB / 0.5744 GB, free: 15.68 MB / 394.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001972/0.2856, allocations: 1.099 MB / 0.5754 GB, free: 14.56 MB / 394.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.783e-05/0.2856, allocations: 0 / 0.5754 GB, free: 14.56 MB / 394.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.629e-05/0.2857, allocations: 32 kB / 0.5755 GB, free: 14.53 MB / 394.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.966e-05/0.2857, allocations: 16 kB / 0.5755 GB, free: 14.52 MB / 394.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001929/0.2877, allocations: 0.9994 MB / 0.5765 GB, free: 13.52 MB / 394.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.005913/0.2936, allocations: 3.862 MB / 0.5802 GB, free: 9.469 MB / 394.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.006916/0.3005, allocations: 2.342 MB / 0.5825 GB, free: 7.031 MB / 394.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001288/0.3018, allocations: 0.6839 MB / 0.5832 GB, free: 6.34 MB / 394.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.000105/0.3019, allocations: 24.97 kB / 0.5832 GB, free: 6.316 MB / 394.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.58e-05/0.302, allocations: 39.56 kB / 0.5833 GB, free: 6.266 MB / 394.7 MB
Notification: Performance of pre-optimization done (n=93): time 2.856e-06/0.302, allocations: 0 / 0.5833 GB, free: 6.266 MB / 394.7 MB
Notification: Performance of matching and sorting (n=93): time 0.02644/0.3284, allocations: 6.841 MB / 0.5899 GB, free: 15.39 MB / 410.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.46e-05/0.3285, allocations: 145.5 kB / 0.5901 GB, free: 15.21 MB / 410.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001495/0.33, allocations: 1.075 MB / 0.5911 GB, free: 14.12 MB / 410.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001362/0.3302, allocations: 41.7 kB / 0.5912 GB, free: 14.08 MB / 410.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003592/0.3306, allocations: 0.489 MB / 0.5916 GB, free: 13.58 MB / 410.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003045/0.3309, allocations: 347 kB / 0.592 GB, free: 13.24 MB / 410.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001849/0.3311, allocations: 145.6 kB / 0.5921 GB, free: 13.09 MB / 410.7 MB
Notification: Performance of setup shared object (initialization): time 8.085e-05/0.3312, allocations: 301.1 kB / 0.5924 GB, free: 12.79 MB / 410.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001483/0.3326, allocations: 0.7574 MB / 0.5931 GB, free: 12.04 MB / 410.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001606/0.3343, allocations: 0.9312 MB / 0.594 GB, free: 11.06 MB / 410.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02178/0.356, allocations: 5.563 MB / 0.5995 GB, free: 5.414 MB / 410.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.392e-06/0.3561, allocations: 0 / 0.5995 GB, free: 5.414 MB / 410.7 MB
Notification: Performance of matching and sorting (n=186) (initialization): time 0.01275/0.3688, allocations: 4.304 MB / 0.6037 GB, free: 1.082 MB / 410.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001394/0.369, allocations: 246.6 kB / 0.6039 GB, free: 0.7891 MB / 410.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.078e-05/0.369, allocations: 4 kB / 0.6039 GB, free: 0.7852 MB / 410.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005254/0.3743, allocations: 1.527 MB / 0.6054 GB, free: 15.25 MB / 426.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001299/0.3756, allocations: 283.6 kB / 0.6057 GB, free: 14.98 MB / 426.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01729/0.3929, allocations: 7.574 MB / 0.6131 GB, free: 7.254 MB / 426.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001634/0.3945, allocations: 194.7 kB / 0.6133 GB, free: 7.062 MB / 426.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001217/0.3947, allocations: 51.98 kB / 0.6133 GB, free: 7.012 MB / 426.7 MB
Notification: Performance of prepare postOptimizeDAE: time 8.897e-06/0.3947, allocations: 4 kB / 0.6133 GB, free: 7.008 MB / 426.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.01246/0.4072, allocations: 4.156 MB / 0.6174 GB, free: 2.832 MB / 426.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.482e-05/0.4072, allocations: 8 kB / 0.6174 GB, free: 2.824 MB / 426.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005163/0.4124, allocations: 1.527 MB / 0.6189 GB, free: 1.293 MB / 426.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001394/0.4138, allocations: 271.6 kB / 0.6191 GB, free: 1.027 MB / 426.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01713/0.4309, allocations: 7.59 MB / 0.6265 GB, free: 9.293 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001529/0.4325, allocations: 174.9 kB / 0.6267 GB, free: 9.121 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001576/0.4327, allocations: 51.98 kB / 0.6268 GB, free: 9.07 MB / 442.7 MB
Warning: Assuming fixed start value for the following 4 variables:
         order4.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order2.delta:VARIABLE(flow=false start = order2.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order4.delta:VARIABLE(flow=false start = order4.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
         order4.e1q:VARIABLE(start = order4.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: 13
 * 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 (156):
 * Single equations (assignments): 153
 * 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): 1 system
   {(20,11,23.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0006401/0.4333, allocations: 379.5 kB / 0.6271 GB, free: 8.684 MB / 442.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003005/0.4336, allocations: 130.4 kB / 0.6272 GB, free: 8.555 MB / 442.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01215/0.4458, allocations: 3.759 MB / 0.6309 GB, free: 4.766 MB / 442.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.358e-05/0.4458, allocations: 3.984 kB / 0.6309 GB, free: 4.762 MB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.078e-06/0.4458, allocations: 3.984 kB / 0.6309 GB, free: 4.758 MB / 442.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002869/0.4461, allocations: 219.3 kB / 0.6311 GB, free: 4.523 MB / 442.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001708/0.4463, allocations: 20 kB / 0.6312 GB, free: 4.504 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001108/0.4474, allocations: 103.9 kB / 0.6313 GB, free: 4.402 MB / 442.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009244/0.4483, allocations: 404.2 kB / 0.6316 GB, free: 4.008 MB / 442.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005506/0.4538, allocations: 2.146 MB / 0.6337 GB, free: 1.828 MB / 442.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.004082/0.4579, allocations: 2.272 MB / 0.636 GB, free: 15.54 MB / 458.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001179/0.4591, allocations: 0.57 MB / 0.6365 GB, free: 14.97 MB / 458.7 MB
Notification: Performance of sorting global known variables: time 0.001111/0.4603, allocations: 0.7272 MB / 0.6372 GB, free: 14.23 MB / 458.7 MB
Notification: Performance of Backend: time 6.41e-07/0.4603, allocations: 0 / 0.6372 GB, free: 14.23 MB / 458.7 MB
Notification: Performance of simCode: created initialization part: time 0.0102/0.4705, allocations: 3.93 MB / 0.6411 GB, free: 10.22 MB / 458.7 MB
Notification: Performance of SimCode: time 0.00394/0.4744, allocations: 3.286 MB / 0.6443 GB, free: 6.875 MB / 458.7 MB
Notification: Performance of Templates: time 0.04652/0.521, allocations: 29.72 MB / 0.6733 GB, free: 9.152 MB / 490.7 MB
make -j1 -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI.makefile
(rm -f OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI.pipe >> ../files/OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI.sim & ./OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Examples.PSATSystems.TwoAreaOld.FourthOrder_AVRI.pipe 2>&1)