Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.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.Tests.Machines.PSAT.Order3test2_perturbation,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation")
translateModel(OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001016/0.001016, allocations: 98.88 kB / 16.02 MB, free: 76 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.326/1.326, allocations: 223.3 MB / 240 MB, free: 5.27 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.001147/0.001147, allocations: 164.6 kB / 291.3 MB, free: 1.941 MB / 238.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5103/0.5103, allocations: 91.93 MB / 434.3 MB, free: 10.28 MB / 302.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004014/0.0004015, allocations: 16 kB / 0.5054 GB, free: 7.234 MB / 382.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3815/0.3819, allocations: 72.46 MB / 0.5762 GB, free: 36.7 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation): time 0.004778/0.3867, allocations: 6.323 MB / 0.5823 GB, free: 32.5 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00343/0.3902, allocations: 1.907 MB / 0.5842 GB, free: 31.14 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0007041/0.3909, allocations: 13.75 kB / 0.5842 GB, free: 31.14 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0004365/0.3914, allocations: 166.8 kB / 0.5844 GB, free: 31.08 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.001595/0.393, allocations: 0.6322 MB / 0.585 GB, free: 30.66 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001125/0.3941, allocations: 0.5706 MB / 0.5856 GB, free: 30.35 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001228/0.3954, allocations: 1.354 MB / 0.5869 GB, free: 29.68 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003953/0.3958, allocations: 284.5 kB / 0.5872 GB, free: 29.57 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0006127/0.3964, allocations: 0.5177 MB / 0.5877 GB, free: 29.33 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0005841/0.397, allocations: 0.6618 MB / 0.5883 GB, free: 29.02 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001021/0.3971, allocations: 54.12 kB / 0.5884 GB, free: 29.02 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0004121/0.3975, allocations: 189.6 kB / 0.5885 GB, free: 28.97 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001429/0.3977, allocations: 224.6 kB / 0.5888 GB, free: 28.84 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.000371/0.398, allocations: 0.5911 MB / 0.5893 GB, free: 28.44 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.0008119/0.3989, allocations: 1.098 MB / 0.5904 GB, free: 28.09 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.721e-06/0.3989, allocations: 5.359 kB / 0.5904 GB, free: 28.09 MB / 430.1 MB
Notification: Performance of FrontEnd: time 1.924e-06/0.3989, allocations: 0 / 0.5904 GB, free: 28.09 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 1.291e-05/0.3989, allocations: 0 / 0.5904 GB, free: 28.09 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 195
 * Number of variables: 195
Notification: Performance of Generate backend data structure: time 0.007164/0.4061, allocations: 2.387 MB / 0.5927 GB, free: 27.05 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.916e-05/0.4061, allocations: 9.594 kB / 0.5927 GB, free: 27.05 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00495/0.4111, allocations: 0.7915 MB / 0.5935 GB, free: 26.89 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001226/0.4123, allocations: 0.769 MB / 0.5943 GB, free: 26.34 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002835/0.4126, allocations: 266.7 kB / 0.5945 GB, free: 26.11 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001644/0.4128, allocations: 79.88 kB / 0.5946 GB, free: 26.09 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002441/0.4153, allocations: 1.169 MB / 0.5957 GB, free: 25.69 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.033e-05/0.4153, allocations: 8.969 kB / 0.5957 GB, free: 25.68 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.738e-05/0.4154, allocations: 37.06 kB / 0.5958 GB, free: 25.65 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.388e-05/0.4154, allocations: 18.44 kB / 0.5958 GB, free: 25.65 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00273/0.4182, allocations: 1.236 MB / 0.597 GB, free: 25.3 MB / 430.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.005938/0.4241, allocations: 3.532 MB / 0.6005 GB, free: 23.31 MB / 430.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002313/0.4264, allocations: 1.518 MB / 0.6019 GB, free: 22.36 MB / 430.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001783/0.4282, allocations: 0.8766 MB / 0.6028 GB, free: 22.06 MB / 430.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 9.946e-05/0.4283, allocations: 14.55 kB / 0.6028 GB, free: 22.05 MB / 430.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.58e-05/0.4284, allocations: 43.66 kB / 0.6029 GB, free: 22 MB / 430.1 MB
Notification: Performance of pre-optimization done (n=97): time 2.885e-06/0.4284, allocations: 4 kB / 0.6029 GB, free: 22 MB / 430.1 MB
Notification: Performance of matching and sorting (n=97): time 0.02899/0.4574, allocations: 8.204 MB / 0.6109 GB, free: 20.56 MB / 430.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.003e-05/0.4575, allocations: 66.78 kB / 0.6109 GB, free: 20.49 MB / 430.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001408/0.4589, allocations: 0.8929 MB / 0.6118 GB, free: 20.12 MB / 430.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002552/0.4591, allocations: 45.48 kB / 0.6118 GB, free: 20.07 MB / 430.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003326/0.4595, allocations: 498.7 kB / 0.6123 GB, free: 19.68 MB / 430.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0003475/0.4598, allocations: 351.2 kB / 0.6127 GB, free: 19.4 MB / 430.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001638/0.46, allocations: 106.8 kB / 0.6128 GB, free: 19.34 MB / 430.1 MB
Notification: Performance of setup shared object (initialization): time 9.02e-05/0.4601, allocations: 346.4 kB / 0.6131 GB, free: 19.02 MB / 430.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001889/0.462, allocations: 0.8134 MB / 0.6139 GB, free: 18.76 MB / 430.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001975/0.464, allocations: 0.9428 MB / 0.6148 GB, free: 18.39 MB / 430.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02023/0.4842, allocations: 5.401 MB / 0.6201 GB, free: 16.24 MB / 430.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.144e-05/0.4843, allocations: 0 / 0.6201 GB, free: 16.24 MB / 430.1 MB
Notification: Performance of matching and sorting (n=147) (initialization): time 0.01392/0.4982, allocations: 5.214 MB / 0.6252 GB, free: 11.01 MB / 430.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.94e-05/0.4983, allocations: 11.98 kB / 0.6252 GB, free: 11 MB / 430.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.392e-05/0.4983, allocations: 3.938 kB / 0.6252 GB, free: 11 MB / 430.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006085/0.5044, allocations: 2.116 MB / 0.6273 GB, free: 8.875 MB / 430.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001107/0.5055, allocations: 223.7 kB / 0.6275 GB, free: 8.656 MB / 430.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0196/0.5251, allocations: 9.572 MB / 0.6368 GB, free: 14.95 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001764/0.5269, allocations: 223 kB / 0.637 GB, free: 14.73 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000109/0.527, allocations: 43.98 kB / 0.6371 GB, free: 14.69 MB / 446.1 MB
Warning: Assuming fixed start value for the following 2 variables:
         order3_Inputs_Outputs1.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order3_Inputs_Outputs1.delta:VARIABLE(flow=false start = order3_Inputs_Outputs1.delta0 unit = "rad" fixed = true )  "Rotor angle" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 10
 * 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 (111):
 * Single equations (assignments): 110
 * 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
   {(25,12)}
Notification: Performance of prepare postOptimizeDAE: time 0.0005423/0.5276, allocations: 246.5 kB / 0.6373 GB, free: 14.44 MB / 446.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002122/0.5278, allocations: 118.5 kB / 0.6374 GB, free: 14.32 MB / 446.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01483/0.5426, allocations: 4.955 MB / 0.6423 GB, free: 9.352 MB / 446.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.913e-05/0.5427, allocations: 12 kB / 0.6423 GB, free: 9.34 MB / 446.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.889e-06/0.5427, allocations: 4 kB / 0.6423 GB, free: 9.336 MB / 446.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.998e-05/0.5428, allocations: 7.922 kB / 0.6423 GB, free: 9.328 MB / 446.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01703/0.5598, allocations: 6.559 MB / 0.6487 GB, free: 2.629 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.627e-05/0.5599, allocations: 0 / 0.6487 GB, free: 2.629 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0008586/0.5607, allocations: 151.8 kB / 0.6488 GB, free: 2.48 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.006521/0.5673, allocations: 2.053 MB / 0.6508 GB, free: 432 kB / 446.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001771/0.5675, allocations: 16 kB / 0.6508 GB, free: 416 kB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01897/0.5864, allocations: 8.223 MB / 0.6589 GB, free: 8.043 MB / 462.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.789e-06/0.5865, allocations: 0 / 0.6589 GB, free: 8.043 MB / 462.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01746/0.604, allocations: 6.001 MB / 0.6647 GB, free: 2.02 MB / 462.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007006/0.6047, allocations: 207.4 kB / 0.6649 GB, free: 1.797 MB / 462.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002239/0.605, allocations: 19.98 kB / 0.665 GB, free: 1.777 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00119/0.6061, allocations: 155.9 kB / 0.6651 GB, free: 1.625 MB / 462.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0007473/0.6069, allocations: 220.6 kB / 0.6653 GB, free: 1.406 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.861e-05/0.607, allocations: 20 kB / 0.6653 GB, free: 1.387 MB / 462.1 MB
Notification: Performance of sorting global known variables: time 0.0008072/0.6078, allocations: 0.6086 MB / 0.6659 GB, free: 0.7695 MB / 462.1 MB
Notification: Performance of sort global known variables: time 1.593e-06/0.6078, allocations: 0 / 0.6659 GB, free: 0.7695 MB / 462.1 MB
Notification: Performance of remove unused functions: time 0.001469/0.6093, allocations: 447.4 kB / 0.6664 GB, free: 340 kB / 462.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 3 (order3_Inputs_Outputs1.delta,order3_Inputs_Outputs1.w,order3_Inputs_Outputs1.e1q)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (64):
 * Single equations (assignments): 63
 * 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
   {(26,10)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0002974/0.6096, allocations: 126.4 kB / 0.6665 GB, free: 204 kB / 462.1 MB
Notification: Performance of simCode: created initialization part: time 0.007521/0.6171, allocations: 2.799 MB / 0.6692 GB, free: 13.38 MB / 478.1 MB
Notification: Performance of simCode: created event and clocks part: time 4.708e-06/0.6172, allocations: 3.938 kB / 0.6692 GB, free: 13.38 MB / 478.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.00529/0.6225, allocations: 1.791 MB / 0.671 GB, free: 11.58 MB / 478.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001063/0.6236, allocations: 92.34 kB / 0.671 GB, free: 11.49 MB / 478.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004052/0.6276, allocations: 2.53 MB / 0.6735 GB, free: 8.93 MB / 478.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006909/0.6284, allocations: 1.099 MB / 0.6746 GB, free: 7.801 MB / 478.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003566/0.6287, allocations: 71.41 kB / 0.6747 GB, free: 7.73 MB / 478.1 MB
Notification: Performance of SimCode: time 1.242e-06/0.6287, allocations: 3.938 kB / 0.6747 GB, free: 7.727 MB / 478.1 MB
Notification: Performance of Templates: time 0.0271/0.6558, allocations: 19.96 MB / 0.6942 GB, free: 3.762 MB / 494.1 MB
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.makefile
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.sim & ./OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.Machines.PSAT.Order3test2_perturbation.pipe 2>&1)