Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.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.Examples.IEEE9.IEEE_9_Buses,tolerance=1e-06,outputFormat="empty",numberOfIntervals=40000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.IEEE9.IEEE_9_Buses") translateModel(OpenIPSL.Examples.IEEE9.IEEE_9_Buses,tolerance=1e-06,outputFormat="empty",numberOfIntervals=40000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.IEEE9.IEEE_9_Buses") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001559/0.001559, allocations: 104.1 kB / 15.88 MB, free: 4 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.277/1.277, allocations: 222.9 MB / 239.5 MB, free: 4.734 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.00125/0.00125, allocations: 196.4 kB / 289.8 MB, free: 2.473 MB / 238.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.4985/0.4985, allocations: 91.9 MB / 431.8 MB, free: 9.418 MB / 302.1 MB Notification: Performance of FrontEnd - loaded program: time 0.002623/0.002623, allocations: 79.86 kB / 0.5018 GB, free: 8.125 MB / 382.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3293/0.332, allocations: 71.27 MB / 0.5714 GB, free: 36.37 MB / 430.1 MB Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.IEEE9.IEEE_9_Buses): time 0.009252/0.3413, allocations: 13.77 MB / 0.5849 GB, free: 27.43 MB / 430.1 MB Notification: Performance of NFInst.instExpressions: time 0.006296/0.3476, allocations: 3.69 MB / 0.5885 GB, free: 24.78 MB / 430.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.002097/0.3497, allocations: 29.66 kB / 0.5885 GB, free: 24.77 MB / 430.1 MB Notification: Performance of NFTyping.typeComponents: time 0.001168/0.3509, allocations: 390.1 kB / 0.5889 GB, free: 24.61 MB / 430.1 MB Notification: Performance of NFTyping.typeBindings: time 0.00347/0.3544, allocations: 1.254 MB / 0.5901 GB, free: 23.77 MB / 430.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.002428/0.3569, allocations: 1.228 MB / 0.5913 GB, free: 23.04 MB / 430.1 MB Notification: Performance of NFFlatten.flatten: time 0.00484/0.3617, allocations: 3.752 MB / 0.595 GB, free: 21.18 MB / 430.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.001312/0.363, allocations: 0.8474 MB / 0.5958 GB, free: 20.71 MB / 430.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.002064/0.3651, allocations: 1.357 MB / 0.5971 GB, free: 20.08 MB / 430.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.001808/0.3669, allocations: 1.754 MB / 0.5989 GB, free: 19.25 MB / 430.1 MB Notification: Performance of NFPackage.collectConstants: time 0.0003741/0.3673, allocations: 174.8 kB / 0.599 GB, free: 19.25 MB / 430.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.000794/0.3681, allocations: 314.3 kB / 0.5993 GB, free: 19.2 MB / 430.1 MB Notification: Performance of NFScalarize.scalarize: time 0.0004557/0.3686, allocations: 0.6328 MB / 0.5999 GB, free: 18.82 MB / 430.1 MB Notification: Performance of NFVerifyModel.verify: time 0.001187/0.3698, allocations: 1.496 MB / 0.6014 GB, free: 17.83 MB / 430.1 MB Notification: Performance of NFConvertDAE.convert: time 0.002817/0.3726, allocations: 3.45 MB / 0.6048 GB, free: 16.55 MB / 430.1 MB Notification: Performance of FrontEnd - DAE generated: time 5.33e-06/0.3726, allocations: 1.094 kB / 0.6048 GB, free: 16.55 MB / 430.1 MB Notification: Performance of FrontEnd: time 8.92e-07/0.3726, allocations: 0 / 0.6048 GB, free: 16.55 MB / 430.1 MB Notification: Performance of Transformations before backend: time 9.733e-05/0.3727, allocations: 0 / 0.6048 GB, free: 16.55 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: 457 * Number of variables: 457 Notification: Performance of Generate backend data structure: time 0.01731/0.39, allocations: 5.327 MB / 0.61 GB, free: 14.02 MB / 430.1 MB Notification: Performance of prepare preOptimizeDAE: time 4.145e-05/0.3901, allocations: 8.969 kB / 0.61 GB, free: 14.02 MB / 430.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01039/0.4005, allocations: 1.614 MB / 0.6116 GB, free: 13.67 MB / 430.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.004267/0.4048, allocations: 1.987 MB / 0.6135 GB, free: 12.44 MB / 430.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003856/0.4052, allocations: 464.4 kB / 0.6139 GB, free: 12.05 MB / 430.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003897/0.4056, allocations: 198.2 kB / 0.6141 GB, free: 11.99 MB / 430.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.004628/0.4102, allocations: 2.389 MB / 0.6165 GB, free: 11.05 MB / 430.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 3.341e-05/0.4103, allocations: 0 / 0.6165 GB, free: 11.05 MB / 430.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001975/0.4105, allocations: 73.55 kB / 0.6165 GB, free: 11 MB / 430.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.031e-05/0.4105, allocations: 43.81 kB / 0.6166 GB, free: 11 MB / 430.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004376/0.4149, allocations: 2.215 MB / 0.6187 GB, free: 10.2 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.0155/0.4304, allocations: 9.103 MB / 0.6276 GB, free: 3.969 MB / 430.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.008999/0.4394, allocations: 4.38 MB / 0.6319 GB, free: 96 kB / 430.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.003009/0.4425, allocations: 1.535 MB / 0.6334 GB, free: 14.55 MB / 446.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.0002071/0.4427, allocations: 41.95 kB / 0.6334 GB, free: 14.51 MB / 446.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.003197/0.4459, allocations: 1.758 MB / 0.6352 GB, free: 12.73 MB / 446.1 MB Notification: Performance of pre-optimization done (n=236): time 4.358e-06/0.4459, allocations: 0 / 0.6352 GB, free: 12.73 MB / 446.1 MB Notification: Performance of matching and sorting (n=236): time 0.0795/0.5254, allocations: 17.9 MB / 0.6526 GB, free: 10.68 MB / 462.1 MB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001217/0.5256, allocations: 239.6 kB / 0.6529 GB, free: 10.4 MB / 462.1 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004276/0.5299, allocations: 2.507 MB / 0.6553 GB, free: 7.875 MB / 462.1 MB Notification: Performance of collectPreVariables (initialization): time 0.0006985/0.5306, allocations: 73.69 kB / 0.6554 GB, free: 7.797 MB / 462.1 MB Notification: Performance of collectInitialEqns (initialization): time 0.0008473/0.5315, allocations: 1.118 MB / 0.6565 GB, free: 6.668 MB / 462.1 MB Notification: Performance of collectInitialBindings (initialization): time 0.001058/0.5325, allocations: 0.8397 MB / 0.6573 GB, free: 5.816 MB / 462.1 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001023/0.5336, allocations: 0.5773 MB / 0.6579 GB, free: 5.23 MB / 462.1 MB Notification: Performance of setup shared object (initialization): time 9.606e-05/0.5337, allocations: 301.1 kB / 0.6581 GB, free: 4.934 MB / 462.1 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.003089/0.5368, allocations: 1.588 MB / 0.6597 GB, free: 3.344 MB / 462.1 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.003271/0.54, allocations: 1.91 MB / 0.6616 GB, free: 1.363 MB / 462.1 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.04478/0.5848, allocations: 11 MB / 0.6723 GB, free: 6.109 MB / 478.1 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.493e-05/0.5849, allocations: 0 / 0.6723 GB, free: 6.109 MB / 478.1 MB Notification: Performance of matching and sorting (n=416) (initialization): time 0.02416/0.6091, allocations: 7.416 MB / 0.6795 GB, free: 14.6 MB / 494.1 MB Notification: Performance of prepare postOptimizeDAE: time 0.0001228/0.6092, allocations: 178.1 kB / 0.6797 GB, free: 14.39 MB / 494.1 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.023e-05/0.6092, allocations: 4 kB / 0.6797 GB, free: 14.39 MB / 494.1 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.01102/0.6203, allocations: 2.815 MB / 0.6825 GB, free: 11.55 MB / 494.1 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003411/0.6237, allocations: 0.6281 MB / 0.6831 GB, free: 10.92 MB / 494.1 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03481/0.6586, allocations: 17.74 MB / 0.7004 GB, free: 8.504 MB / 0.4981 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004461/0.663, allocations: 409.2 kB / 0.7008 GB, free: 8.102 MB / 0.4981 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007995/0.6639, allocations: 116 kB / 0.7009 GB, free: 7.988 MB / 0.4981 GB Warning: Assuming fixed start value for the following 9 variables: gen3.gen.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real gen2.gen.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real gen1.gen.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real gen1.gen.delta:VARIABLE(flow=false start = gen1.gen.delta0 unit = "rad" fixed = true ) "Rotor angle" type: Real gen1.gen.e1q:VARIABLE(start = gen1.gen.e1q0 unit = "1" fixed = true ) "q-axis transient voltage" type: Real gen1.gen.e1d:VARIABLE(start = gen1.gen.e1d0 unit = "1" fixed = true ) "d-axis transient voltage" type: Real gen1.AVR.firstOrder2.y:VARIABLE(flow=false start = gen1.AVR.firstOrder2.y_start fixed = true ) "Connector of Real output signal" type: Real gen2.gen.delta:VARIABLE(flow=false start = gen2.gen.delta0 unit = "rad" fixed = true ) "Rotor angle" type: Real gen2.gen.e1q:VARIABLE(start = gen2.gen.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: 22 * Number of states: 0 () * Number of discrete variables: 6 ($whenCondition2,$whenCondition1,$whenCondition4,$whenCondition3,$whenCondition6,$whenCondition5) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (345): * Single equations (assignments): 332 * Array equations: 0 * Algorithm blocks: 6 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 2 * Torn equation systems: 5 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 2 systems {(2,100.0%), (2,100.0%)} * Non-linear Jacobian (size): 0 systems * Without analytic Jacobian (size): 0 systems Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 2 systems {(1,1,100.0%), (1,1,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 3 systems {(4,2), (29,30), (1,4)} Notification: Performance of prepare postOptimizeDAE: time 0.001936/0.6658, allocations: 0.6169 MB / 0.7015 GB, free: 7.344 MB / 0.4981 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001101/0.6669, allocations: 300.2 kB / 0.7018 GB, free: 7.047 MB / 0.4981 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02367/0.6906, allocations: 7.349 MB / 0.709 GB, free: 15.64 MB / 0.5137 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.383e-05/0.6907, allocations: 24 kB / 0.709 GB, free: 15.62 MB / 0.5137 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 8.897e-06/0.6907, allocations: 0 / 0.709 GB, free: 15.62 MB / 0.5137 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.84e-05/0.6908, allocations: 19.89 kB / 0.709 GB, free: 15.6 MB / 0.5137 GB 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 postOpt removeSimpleEquations (simulation): time 0.03088/0.7217, allocations: 11.44 MB / 0.7202 GB, free: 3.895 MB / 0.5137 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.248e-05/0.7217, allocations: 0 / 0.7202 GB, free: 3.895 MB / 0.5137 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001517/0.7232, allocations: 223.7 kB / 0.7204 GB, free: 3.676 MB / 0.5137 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.01222/0.7355, allocations: 3.436 MB / 0.7238 GB, free: 232 kB / 0.5137 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0006427/0.7362, allocations: 59.98 kB / 0.7238 GB, free: 172 kB / 0.5137 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04072/0.7769, allocations: 19.05 MB / 0.7424 GB, free: 12.54 MB / 0.545 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.467e-06/0.7769, allocations: 0 / 0.7424 GB, free: 12.54 MB / 0.545 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02787/0.8048, allocations: 9.13 MB / 0.7513 GB, free: 3.34 MB / 0.545 GB Notification: Performance of postOpt removeConstants (simulation): time 0.00123/0.8061, allocations: 432.8 kB / 0.7517 GB, free: 2.895 MB / 0.545 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001117/0.8072, allocations: 55.94 kB / 0.7518 GB, free: 2.84 MB / 0.545 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003094/0.8103, allocations: 215.8 kB / 0.752 GB, free: 2.629 MB / 0.545 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001399/0.8118, allocations: 378.4 kB / 0.7524 GB, free: 2.258 MB / 0.545 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003653/0.8121, allocations: 67.98 kB / 0.7524 GB, free: 2.191 MB / 0.545 GB Notification: Performance of sorting global known variables: time 0.003248/0.8154, allocations: 1.669 MB / 0.7541 GB, free: 0.5195 MB / 0.545 GB Notification: Performance of sort global known variables: time 2.7e-07/0.8154, allocations: 0 / 0.7541 GB, free: 0.5195 MB / 0.545 GB Notification: Performance of remove unused functions: time 0.004053/0.8195, allocations: 1.026 MB / 0.7551 GB, free: 15.49 MB / 0.5606 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 4 * Number of states: 24 (gen1.gen.delta,gen1.gen.w,gen1.gen.e1q,gen1.gen.e1d,gen1.AVR.ExcitationSystem.x_scaled[1],gen1.AVR.derivativeBlock.x,gen1.AVR.firstOrder2.y,gen1.AVR.simpleLagLim.state,gen2.gen.delta,gen2.gen.w,gen2.gen.e1q,gen2.gen.e1d,gen2.AVR.ExcitationSystem.x_scaled[1],gen2.AVR.derivativeBlock.x,gen2.AVR.firstOrder2.y,gen2.AVR.simpleLagLim.state,gen3.gen.delta,gen3.gen.w,gen3.gen.e1q,gen3.gen.e1d,gen3.AVR.ExcitationSystem.x_scaled[1],gen3.AVR.derivativeBlock.x,gen3.AVR.firstOrder2.y,gen3.AVR.simpleLagLim.state) * Number of discrete variables: 6 ($whenCondition5,$whenCondition6,$whenCondition3,$whenCondition4,$whenCondition1,$whenCondition2) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (142): * Single equations (assignments): 135 * Array equations: 0 * Algorithm blocks: 3 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 4 * 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): 4 systems {(41,45), (1,6), (1,6), (1,6)} Notification: Performance of Backend phase and start with SimCode phase: time 0.0005633/0.82, allocations: 173.3 kB / 0.7552 GB, free: 15.32 MB / 0.5606 GB Notification: Performance of simCode: created initialization part: time 0.01282/0.8329, allocations: 5.725 MB / 0.7608 GB, free: 9.414 MB / 0.5606 GB Notification: Performance of simCode: created event and clocks part: time 6.553e-06/0.8329, allocations: 0 / 0.7608 GB, free: 9.414 MB / 0.5606 GB Notification: Performance of simCode: created simulation system equations: time 0.009177/0.8421, allocations: 3.519 MB / 0.7642 GB, free: 5.809 MB / 0.5606 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003989/0.8461, allocations: 289.9 kB / 0.7645 GB, free: 5.527 MB / 0.5606 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2186/1.065, allocations: 6.569 MB / 0.7709 GB, free: 162.9 MB / 0.5606 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001307/1.066, allocations: 1.781 MB / 0.7727 GB, free: 162.2 MB / 0.5606 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001212/1.067, allocations: 168.5 kB / 0.7728 GB, free: 162.2 MB / 0.5606 GB Notification: Performance of SimCode: time 8.41e-07/1.067, allocations: 0.8438 kB / 0.7728 GB, free: 162.2 MB / 0.5606 GB Notification: Performance of Templates: time 0.08695/1.154, allocations: 102.2 MB / 0.8726 GB, free: 89.85 MB / 0.5606 GB make -j1 -f OpenIPSL_dev_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.makefile (rm -f OpenIPSL_dev_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.sim & ./OpenIPSL_dev_OpenIPSL.Examples.IEEE9.IEEE_9_Buses -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Examples.IEEE9.IEEE_9_Buses.pipe 2>&1)