Running: ./testmodel.py --libraries=/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_release_OpenIPSL.Examples.Machines.PSAT.Order6test2.conf.json
loadFile("/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/OpenIPSL 2.0.0-master/package.mo", uses=false)
Using package OpenIPSL with version 2.0.0-beta.1 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/OpenIPSL 2.0.0-master/package.mo)
Using package ModelicaServices with version 4.0.0 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Using package Modelica with version 4.0.0 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Running command: translateModel(OpenIPSL.Examples.Machines.PSAT.Order6test2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.Machines.PSAT.Order6test2")
translateModel(OpenIPSL.Examples.Machines.PSAT.Order6test2,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_release_OpenIPSL.Examples.Machines.PSAT.Order6test2")
Notification: Performance of loadFile(/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.00103/0.00103, allocations: 178.4 kB / 15.43 MB, free: 5.77 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.448/1.448, allocations: 225.7 MB / 241.9 MB, free: 396 kB / 186.7 MB
Notification: Performance of loadFile(/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008421/0.0008421, allocations: 90.09 kB / 293.1 MB, free: 12.89 MB / 234.7 MB
Notification: Performance of loadFile(/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/converted-libraries/.openmodelica/libraries/OpenIPSL 2.0.0-master/package.mo): time 0.1709/0.1709, allocations: 43.17 MB / 387.4 MB, free: 15.5 MB / 330.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003149/0.0003149, allocations: 10.25 kB / 458.6 MB, free: 55.43 MB / 330.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1378/0.1382, allocations: 60.5 MB / 0.5069 GB, free: 10.86 MB / 346.7 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.Machines.PSAT.Order6test2): time 0.007846/0.1461, allocations: 5.481 MB / 0.5123 GB, free: 5.355 MB / 346.7 MB
Notification: Performance of NFInst.instExpressions: time 0.005558/0.1517, allocations: 2.397 MB / 0.5146 GB, free: 2.805 MB / 346.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008344/0.1526, allocations: 8 kB / 0.5146 GB, free: 2.797 MB / 346.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0005747/0.1532, allocations: 158.9 kB / 0.5148 GB, free: 2.641 MB / 346.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.002238/0.1554, allocations: 0.9257 MB / 0.5157 GB, free: 1.641 MB / 346.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001688/0.1571, allocations: 0.6733 MB / 0.5163 GB, free: 0.9297 MB / 346.7 MB
Notification: Performance of NFFlatten.flatten: time 0.002015/0.1591, allocations: 1.292 MB / 0.5176 GB, free: 15.63 MB / 362.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0005901/0.1597, allocations: 235.7 kB / 0.5178 GB, free: 15.39 MB / 362.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0009176/0.1607, allocations: 0.4976 MB / 0.5183 GB, free: 14.89 MB / 362.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0009802/0.1617, allocations: 0.6337 MB / 0.5189 GB, free: 14.25 MB / 362.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001556/0.1618, allocations: 52 kB / 0.519 GB, free: 14.2 MB / 362.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0006402/0.1625, allocations: 183.8 kB / 0.5191 GB, free: 14.02 MB / 362.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0002684/0.1628, allocations: 206.9 kB / 0.5193 GB, free: 13.82 MB / 362.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0006698/0.1635, allocations: 0.5491 MB / 0.5199 GB, free: 13.26 MB / 362.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.001833/0.1653, allocations: 1.335 MB / 0.5212 GB, free: 11.92 MB / 362.7 MB
Notification: Performance of FrontEnd - DAE generated: time 8.386e-06/0.1653, allocations: 4 kB / 0.5212 GB, free: 11.92 MB / 362.7 MB
Notification: Performance of FrontEnd: time 2.715e-06/0.1653, allocations: 0 / 0.5212 GB, free: 11.92 MB / 362.7 MB
Notification: Performance of Transformations before backend: time 1.059e-05/0.1654, allocations: 0 / 0.5212 GB, free: 11.92 MB / 362.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 170
 * Number of variables: 170
Notification: Performance of Generate backend data structure: time 0.01045/0.1758, allocations: 2.302 MB / 0.5234 GB, free: 9.562 MB / 362.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.429e-05/0.1759, allocations: 8.031 kB / 0.5234 GB, free: 9.555 MB / 362.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00774/0.1836, allocations: 0.7635 MB / 0.5242 GB, free: 8.789 MB / 362.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001937/0.1856, allocations: 0.7516 MB / 0.5249 GB, free: 7.996 MB / 362.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004447/0.1861, allocations: 260.2 kB / 0.5252 GB, free: 7.703 MB / 362.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003302/0.1864, allocations: 72 kB / 0.5252 GB, free: 7.633 MB / 362.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003596/0.1901, allocations: 1.061 MB / 0.5263 GB, free: 6.566 MB / 362.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.598e-05/0.1901, allocations: 0 / 0.5263 GB, free: 6.566 MB / 362.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001437/0.1903, allocations: 28 kB / 0.5263 GB, free: 6.539 MB / 362.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.175e-05/0.1903, allocations: 16 kB / 0.5263 GB, free: 6.523 MB / 362.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00352/0.1938, allocations: 1.074 MB / 0.5274 GB, free: 5.449 MB / 362.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.008637/0.2025, allocations: 3.348 MB / 0.5306 GB, free: 1.941 MB / 362.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.003364/0.2059, allocations: 1.415 MB / 0.532 GB, free: 412 kB / 362.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.2881/0.494, allocations: 0.7903 MB / 0.5328 GB, free: 32.38 MB / 362.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001319/0.4942, allocations: 16.05 kB / 0.5328 GB, free: 32.38 MB / 362.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.957e-05/0.4942, allocations: 42.48 kB / 0.5328 GB, free: 32.34 MB / 362.7 MB
Notification: Performance of pre-optimization done (n=85): time 3.096e-06/0.4942, allocations: 0 / 0.5328 GB, free: 32.34 MB / 362.7 MB
Notification: Performance of matching and sorting (n=85): time 0.02925/0.5235, allocations: 8.145 MB / 0.5408 GB, free: 31.57 MB / 362.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.433e-05/0.5236, allocations: 65.3 kB / 0.5409 GB, free: 31.51 MB / 362.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001285/0.5249, allocations: 0.8392 MB / 0.5417 GB, free: 31.14 MB / 362.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002006/0.5251, allocations: 41.48 kB / 0.5417 GB, free: 31.1 MB / 362.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003083/0.5254, allocations: 445.3 kB / 0.5421 GB, free: 30.82 MB / 362.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.000278/0.5257, allocations: 309.7 kB / 0.5424 GB, free: 30.6 MB / 362.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 7.535e-05/0.5258, allocations: 20.88 kB / 0.5425 GB, free: 30.58 MB / 362.7 MB
Notification: Performance of setup shared object (initialization): time 8.382e-05/0.5259, allocations: 348.6 kB / 0.5428 GB, free: 30.26 MB / 362.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001604/0.5275, allocations: 0.757 MB / 0.5435 GB, free: 30 MB / 362.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001675/0.5292, allocations: 0.889 MB / 0.5444 GB, free: 29.63 MB / 362.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02009/0.5493, allocations: 5.373 MB / 0.5496 GB, free: 27.93 MB / 362.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.47e-05/0.5493, allocations: 1.812 kB / 0.5497 GB, free: 27.93 MB / 362.7 MB
Notification: Performance of matching and sorting (n=143) (initialization): time 0.0141/0.5634, allocations: 5.12 MB / 0.5547 GB, free: 25.74 MB / 362.7 MB
Notification: Performance of prepare postOptimizeDAE: time 3.364e-05/0.5635, allocations: 7.75 kB / 0.5547 GB, free: 25.74 MB / 362.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.351e-05/0.5635, allocations: 7.969 kB / 0.5547 GB, free: 25.74 MB / 362.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.006131/0.5697, allocations: 2.184 MB / 0.5568 GB, free: 23.71 MB / 362.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009453/0.5706, allocations: 183.8 kB / 0.557 GB, free: 23.54 MB / 362.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01921/0.5898, allocations: 10.87 MB / 0.5676 GB, free: 12.4 MB / 362.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001634/0.5915, allocations: 198.9 kB / 0.5678 GB, free: 12.21 MB / 362.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.055e-05/0.5916, allocations: 36 kB / 0.5678 GB, free: 12.17 MB / 362.7 MB
Warning: Assuming fixed start value for the following 2 variables:
         order6Type2_1.w:VARIABLE(flow=false start = 1.0 fixed = true )  "Rotor speed [pu]" type: Real
         order6Type2_1.e2d:VARIABLE(start = order6Type2_1.e2d0 unit = "1" fixed = true )  "d-axis sub-transient voltage" 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 (107):
 * Single equations (assignments): 105
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(24,12)}
Notification: Performance of prepare postOptimizeDAE: time 0.0003987/0.592, allocations: 240.8 kB / 0.568 GB, free: 11.93 MB / 362.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001433/0.5922, allocations: 106.6 kB / 0.5681 GB, free: 11.82 MB / 362.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01184/0.604, allocations: 4.786 MB / 0.5728 GB, free: 7.023 MB / 362.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.111e-05/0.6041, allocations: 8 kB / 0.5728 GB, free: 7.016 MB / 362.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.316e-06/0.6041, allocations: 3.938 kB / 0.5728 GB, free: 7.012 MB / 362.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.335e-05/0.6041, allocations: 3.984 kB / 0.5728 GB, free: 7.008 MB / 362.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01325/0.6174, allocations: 6.346 MB / 0.579 GB, free: 0.543 MB / 362.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.871e-06/0.6174, allocations: 0 / 0.579 GB, free: 0.543 MB / 362.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0005262/0.6179, allocations: 99.89 kB / 0.5791 GB, free: 456 kB / 362.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.005747/0.6237, allocations: 2.064 MB / 0.5811 GB, free: 14.38 MB / 378.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 8.658e-05/0.6238, allocations: 19.98 kB / 0.5812 GB, free: 14.36 MB / 378.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01711/0.6409, allocations: 8.192 MB / 0.5892 GB, free: 6.031 MB / 378.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.778e-06/0.641, allocations: 1.641 kB / 0.5892 GB, free: 6.031 MB / 378.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01473/0.6557, allocations: 5.75 MB / 0.5948 GB, free: 268 kB / 378.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.00038/0.6561, allocations: 195.6 kB / 0.595 GB, free: 52 kB / 378.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001501/0.6563, allocations: 16 kB / 0.595 GB, free: 36 kB / 378.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009502/0.6572, allocations: 131.9 kB / 0.5951 GB, free: 15.91 MB / 394.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006166/0.6579, allocations: 196.6 kB / 0.5953 GB, free: 15.71 MB / 394.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.829e-05/0.6579, allocations: 20 kB / 0.5953 GB, free: 15.69 MB / 394.7 MB
Notification: Performance of sorting global known variables: time 0.0007184/0.6587, allocations: 0.5663 MB / 0.5959 GB, free: 15.11 MB / 394.7 MB
Notification: Performance of sort global known variables: time 1.2e-07/0.6587, allocations: 0 / 0.5959 GB, free: 15.11 MB / 394.7 MB
Notification: Performance of remove unused functions: time 0.001208/0.6599, allocations: 434.1 kB / 0.5963 GB, free: 14.69 MB / 394.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 6 (order6Type2_1.delta,order6Type2_1.w,order6Type2_1.e1q,order6Type2_1.e1d,order6Type2_1.e2q,order6Type2_1.e2d)
 * 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 (52):
 * Single equations (assignments): 51
 * 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.0002572/0.6601, allocations: 135.6 kB / 0.5964 GB, free: 14.55 MB / 394.7 MB
Notification: Performance of simCode: created initialization part: time 0.006739/0.6669, allocations: 2.761 MB / 0.5991 GB, free: 11.77 MB / 394.7 MB
Notification: Performance of simCode: created event and clocks part: time 3.046e-06/0.6669, allocations: 2.516 kB / 0.5991 GB, free: 11.77 MB / 394.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.004504/0.6714, allocations: 1.71 MB / 0.6008 GB, free: 10.04 MB / 394.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0006389/0.6721, allocations: 87.33 kB / 0.6009 GB, free: 9.953 MB / 394.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003239/0.6753, allocations: 2.406 MB / 0.6032 GB, free: 7.523 MB / 394.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006347/0.676, allocations: 1.045 MB / 0.6042 GB, free: 6.453 MB / 394.7 MB
Notification: Performance of simCode: alias equations: time 0.0003141/0.6763, allocations: 86.44 kB / 0.6043 GB, free: 6.363 MB / 394.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000205/0.6765, allocations: 59.42 kB / 0.6044 GB, free: 6.305 MB / 394.7 MB
Notification: Performance of SimCode: time 5.71e-07/0.6765, allocations: 0 / 0.6044 GB, free: 6.305 MB / 394.7 MB
Notification: Performance of Templates: time 0.0322/0.7087, allocations: 20.62 MB / 0.6245 GB, free: 1.699 MB / 410.7 MB
make -j1 -f OpenIPSL_release_OpenIPSL.Examples.Machines.PSAT.Order6test2.makefile
(rm -f OpenIPSL_release_OpenIPSL.Examples.Machines.PSAT.Order6test2.pipe ; mkfifo OpenIPSL_release_OpenIPSL.Examples.Machines.PSAT.Order6test2.pipe ; head -c 1048576 < OpenIPSL_release_OpenIPSL.Examples.Machines.PSAT.Order6test2.pipe >> ../files/OpenIPSL_release_OpenIPSL.Examples.Machines.PSAT.Order6test2.sim & ./OpenIPSL_release_OpenIPSL.Examples.Machines.PSAT.Order6test2 -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_release_OpenIPSL.Examples.Machines.PSAT.Order6test2.pipe 2>&1)