Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.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.PSATSystems.TwoArea.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI") translateModel(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008796/0.0008794, allocations: 86.56 kB / 15.06 MB, free: 1.09 MB / 14.72 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.338/1.338, allocations: 223.2 MB / 239 MB, free: 5.473 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.0009551/0.0009552, allocations: 180.4 kB / 290.3 MB, free: 2.148 MB / 238.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.5113/0.5113, allocations: 91.92 MB / 433.3 MB, free: 10.39 MB / 302.1 MB Notification: Performance of FrontEnd - loaded program: time 0.000394/0.000394, allocations: 8 kB / 0.5042 GB, free: 7.66 MB / 382.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3544/0.3548, allocations: 72.48 MB / 0.5749 GB, free: 36.48 MB / 430.1 MB Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI): time 0.0133/0.3681, allocations: 20.86 MB / 0.5953 GB, free: 21.78 MB / 430.1 MB Notification: Performance of NFInst.instExpressions: time 0.0104/0.3786, allocations: 4.994 MB / 0.6002 GB, free: 18.1 MB / 430.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.004084/0.3827, allocations: 50.72 kB / 0.6002 GB, free: 18.1 MB / 430.1 MB Notification: Performance of NFTyping.typeComponents: time 0.002177/0.3849, allocations: 0.6177 MB / 0.6008 GB, free: 17.83 MB / 430.1 MB Notification: Performance of NFTyping.typeBindings: time 0.007685/0.3926, allocations: 1.761 MB / 0.6026 GB, free: 16.59 MB / 430.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.005263/0.3979, allocations: 1.784 MB / 0.6043 GB, free: 15.64 MB / 430.1 MB Notification: Performance of NFFlatten.flatten: time 0.009006/0.4069, allocations: 5.194 MB / 0.6094 GB, free: 12.92 MB / 430.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.002536/0.4095, allocations: 1.047 MB / 0.6104 GB, free: 12.44 MB / 430.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.004257/0.4137, allocations: 2.005 MB / 0.6124 GB, free: 11.52 MB / 430.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.005655/0.4194, allocations: 2.603 MB / 0.6149 GB, free: 10.33 MB / 430.1 MB Notification: Performance of NFPackage.collectConstants: time 0.002069/0.4215, allocations: 236.3 kB / 0.6151 GB, free: 10.33 MB / 430.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.002488/0.424, allocations: 374.3 kB / 0.6155 GB, free: 10.29 MB / 430.1 MB Notification: Performance of NFScalarize.scalarize: time 0.001081/0.4251, allocations: 0.9184 MB / 0.6164 GB, free: 9.73 MB / 430.1 MB Notification: Performance of NFVerifyModel.verify: time 0.002115/0.4273, allocations: 0.836 MB / 0.6172 GB, free: 9.234 MB / 430.1 MB Notification: Performance of NFConvertDAE.convert: time 0.007339/0.4346, allocations: 4.651 MB / 0.6217 GB, free: 7.754 MB / 430.1 MB Notification: Performance of FrontEnd - DAE generated: time 7.805e-06/0.4346, allocations: 1.25 kB / 0.6217 GB, free: 7.754 MB / 430.1 MB Notification: Performance of FrontEnd: time 2.234e-06/0.4346, allocations: 7.938 kB / 0.6217 GB, free: 7.746 MB / 430.1 MB Notification: Performance of Transformations before backend: time 0.0003226/0.435, allocations: 0 / 0.6217 GB, free: 7.746 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: 891 * Number of variables: 891 Notification: Performance of Generate backend data structure: time 0.02914/0.4641, allocations: 8.505 MB / 0.63 GB, free: 4.133 MB / 430.1 MB Notification: Performance of prepare preOptimizeDAE: time 5.632e-05/0.4642, allocations: 13.09 kB / 0.6301 GB, free: 4.129 MB / 430.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02672/0.4909, allocations: 4.052 MB / 0.634 GB, free: 1.262 MB / 430.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.006527/0.4975, allocations: 2.522 MB / 0.6365 GB, free: 14.66 MB / 446.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000915/0.4984, allocations: 0.9596 MB / 0.6374 GB, free: 13.57 MB / 446.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008858/0.4994, allocations: 352 kB / 0.6378 GB, free: 13.23 MB / 446.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.01003/0.5094, allocations: 4.978 MB / 0.6426 GB, free: 8.219 MB / 446.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 5.815e-05/0.5095, allocations: 4 kB / 0.6426 GB, free: 8.215 MB / 446.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003824/0.5099, allocations: 140 kB / 0.6427 GB, free: 8.078 MB / 446.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.874e-05/0.5099, allocations: 87.95 kB / 0.6428 GB, free: 7.992 MB / 446.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.009003/0.5189, allocations: 4.513 MB / 0.6472 GB, free: 3.469 MB / 446.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.0385/0.5575, allocations: 18.9 MB / 0.6657 GB, free: 15.37 MB / 478.1 MB Notification: Performance of preOpt comSubExp (simulation): time 0.01345/0.571, allocations: 5.721 MB / 0.6713 GB, free: 9.562 MB / 478.1 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.006535/0.5775, allocations: 3.052 MB / 0.6743 GB, free: 6.5 MB / 478.1 MB Notification: Performance of preOpt evalFunc (simulation): time 0.0007628/0.5783, allocations: 70.83 kB / 0.6743 GB, free: 6.434 MB / 478.1 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.783e-05/0.5784, allocations: 69.55 kB / 0.6744 GB, free: 6.355 MB / 478.1 MB Notification: Performance of pre-optimization done (n=381): time 6.723e-06/0.5784, allocations: 0 / 0.6744 GB, free: 6.355 MB / 478.1 MB Notification: Performance of matching and sorting (n=381): time 0.2825/0.8609, allocations: 79.22 MB / 0.7518 GB, free: 6.969 MB / 0.545 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.000105/0.8611, allocations: 167.5 kB / 0.7519 GB, free: 6.773 MB / 0.545 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005967/0.8671, allocations: 3.215 MB / 0.7551 GB, free: 3.539 MB / 0.545 GB Notification: Performance of collectPreVariables (initialization): time 0.001112/0.8682, allocations: 89.7 kB / 0.7551 GB, free: 3.445 MB / 0.545 GB Notification: Performance of collectInitialEqns (initialization): time 0.00168/0.8699, allocations: 1.982 MB / 0.7571 GB, free: 1.449 MB / 0.545 GB Notification: Performance of collectInitialBindings (initialization): time 0.002393/0.8723, allocations: 1.313 MB / 0.7584 GB, free: 120 kB / 0.545 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009542/0.8733, allocations: 84 kB / 0.7584 GB, free: 36 kB / 0.545 GB Notification: Performance of setup shared object (initialization): time 0.0003518/0.8736, allocations: 463 kB / 0.7589 GB, free: 15.57 MB / 0.5606 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.006444/0.8801, allocations: 2.843 MB / 0.7617 GB, free: 12.72 MB / 0.5606 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.006682/0.8868, allocations: 3.479 MB / 0.7651 GB, free: 9.016 MB / 0.5606 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.006711/0.8935, allocations: 3.123 MB / 0.7681 GB, free: 5.875 MB / 0.5606 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.939e-05/0.8935, allocations: 4 kB / 0.7681 GB, free: 5.871 MB / 0.5606 GB Notification: Performance of matching and sorting (n=532) (initialization): time 0.3816/1.275, allocations: 20.69 MB / 0.7883 GB, free: 158.4 MB / 0.5606 GB Notification: Performance of prepare postOptimizeDAE: time 0.0003303/1.276, allocations: 0.5921 MB / 0.7889 GB, free: 157.7 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.588e-05/1.276, allocations: 13.91 kB / 0.7889 GB, free: 157.7 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.02628/1.302, allocations: 8.525 MB / 0.7972 GB, free: 157.2 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003656/1.306, allocations: 0.6746 MB / 0.7979 GB, free: 157.1 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08398/1.39, allocations: 35.1 MB / 0.8322 GB, free: 138.8 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006826/1.396, allocations: 0.7392 MB / 0.8329 GB, free: 138.1 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001024/1.397, allocations: 159.8 kB / 0.8331 GB, free: 138 MB / 0.5606 GB Notification: Performance of prepare postOptimizeDAE: time 1.058e-05/1.397, allocations: 4 kB / 0.8331 GB, free: 138 MB / 0.5606 GB Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.05204/1.45, allocations: 20.25 MB / 0.8528 GB, free: 117.7 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.661e-05/1.45, allocations: 12 kB / 0.8528 GB, free: 117.7 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.02345/1.473, allocations: 8.528 MB / 0.8612 GB, free: 109.1 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003467/1.477, allocations: 0.667 MB / 0.8618 GB, free: 108.5 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.08708/1.564, allocations: 35.09 MB / 0.8961 GB, free: 73.24 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006398/1.57, allocations: 0.7031 MB / 0.8968 GB, free: 72.56 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001324/1.572, allocations: 148 kB / 0.8969 GB, free: 72.41 MB / 0.5606 GB Warning: Assuming fixed start value for the following 5 variables: order5.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real order3.w:VARIABLE(flow=false start = 1.0 fixed = true ) "Rotor speed [pu]" type: Real order5.e2d:VARIABLE(start = order5.e2d0 unit = "1" fixed = true ) "d-axis sub-transient voltage" type: Real order5.e2q:VARIABLE(start = order5.e2q0 unit = "1" fixed = true ) "q-axis sub-transient voltage" type: Real order5.e1q:VARIABLE(start = order5.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: 65 * 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 (398): * Single equations (assignments): 395 * Array equations: 0 * Algorithm blocks: 2 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (linear and non-linear blocks): 0 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems: 0 * Non-linear torn systems: 1 {92 43} Notification: Performance of prepare postOptimizeDAE: time 0.003555/1.575, allocations: 0.7561 MB / 0.8977 GB, free: 71.64 MB / 0.5606 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002146/1.577, allocations: 458.3 kB / 0.8981 GB, free: 71.19 MB / 0.5606 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.05223/1.63, allocations: 20.52 MB / 0.9181 GB, free: 50.61 MB / 0.5606 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.013e-05/1.63, allocations: 36 kB / 0.9182 GB, free: 50.57 MB / 0.5606 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 7.398e-05/1.63, allocations: 28 kB / 0.9182 GB, free: 50.55 MB / 0.5606 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001029/1.63, allocations: 19.92 kB / 0.9182 GB, free: 50.53 MB / 0.5606 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.06447/1.694, allocations: 25.82 MB / 0.9434 GB, free: 24.62 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.394e-05/1.694, allocations: 4 kB / 0.9434 GB, free: 24.62 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00243/1.697, allocations: 411.4 kB / 0.9438 GB, free: 24.21 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.02348/1.72, allocations: 8.568 MB / 0.9522 GB, free: 15.64 MB / 0.5606 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001119/1.721, allocations: 71.98 kB / 0.9523 GB, free: 15.57 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.07814/1.8, allocations: 30.63 MB / 0.9822 GB, free: 0.7734 MB / 0.5762 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.507e-06/1.8, allocations: 4 kB / 0.9822 GB, free: 0.7695 MB / 0.5762 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.3306/2.13, allocations: 24.14 MB / 1.006 GB, free: 175.1 MB / 0.5919 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0016/2.132, allocations: 0.5594 MB / 1.006 GB, free: 175 MB / 0.5919 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001276/2.133, allocations: 72.64 kB / 1.006 GB, free: 175 MB / 0.5919 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004456/2.138, allocations: 0.4976 MB / 1.007 GB, free: 175 MB / 0.5919 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002914/2.141, allocations: 0.7717 MB / 1.008 GB, free: 175 MB / 0.5919 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003334/2.141, allocations: 89.02 kB / 1.008 GB, free: 175 MB / 0.5919 GB Notification: Performance of sorting global known variables: time 0.003327/2.144, allocations: 2.106 MB / 1.01 GB, free: 174.8 MB / 0.5919 GB Notification: Performance of sort global known variables: time 6.41e-07/2.144, allocations: 1.406 kB / 1.01 GB, free: 174.8 MB / 0.5919 GB Notification: Performance of remove unused functions: time 0.01054/2.155, allocations: 2.977 MB / 1.013 GB, free: 174.6 MB / 0.5919 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 2 * Number of states: 12 (order3.delta,order3.w,order3.e1q,order5.delta,order5.w,order5.e1q,order5.e2q,order5.e2d,aVRTypeI.firstOrder.y,aVRTypeI.firstOrder2.y,aVRTypeI.transferFunction.x_scaled[1],aVRTypeI.transferFunction.x_scaled[2]) * 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 (251): * Single equations (assignments): 249 * Array equations: 0 * Algorithm blocks: 1 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (linear and non-linear blocks): 0 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems: 1 {(96,5.5%) 40} * Non-linear torn systems: 0 Notification: Performance of Backend phase and start with SimCode phase: time 0.000616/2.155, allocations: 194.5 kB / 1.013 GB, free: 174.5 MB / 0.5919 GB Notification: Performance of simCode: created initialization part: time 0.04397/2.199, allocations: 17.62 MB / 1.03 GB, free: 171.2 MB / 0.5919 GB Notification: Performance of simCode: created event and clocks part: time 7.234e-06/2.2, allocations: 0 / 1.03 GB, free: 171.2 MB / 0.5919 GB Notification: Performance of simCode: created simulation system equations: time 0.01714/2.217, allocations: 6.879 MB / 1.037 GB, free: 169.3 MB / 0.5919 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003505/2.22, allocations: 386.8 kB / 1.037 GB, free: 169.1 MB / 0.5919 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01361/2.234, allocations: 9.476 MB / 1.046 GB, free: 163.9 MB / 0.5919 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001668/2.236, allocations: 2.502 MB / 1.049 GB, free: 163.1 MB / 0.5919 GB Notification: Performance of simCode: alias equations: time 0.002155/2.238, allocations: 455.1 kB / 1.049 GB, free: 163 MB / 0.5919 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008557/2.239, allocations: 232.2 kB / 1.049 GB, free: 163 MB / 0.5919 GB Notification: Performance of SimCode: time 7.92e-07/2.239, allocations: 0 / 1.049 GB, free: 163 MB / 0.5919 GB Notification: Performance of Templates: time 0.1336/2.372, allocations: 109.3 MB / 1.156 GB, free: 61.3 MB / 0.5919 GB make -j1 -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.makefile (rm -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.sim & ./OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRI.pipe 2>&1)