Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.000738/0.000738, allocations: 80.44 kB / 19.17 MB, free: 0.7422 MB / 13.93 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 0.8716/0.8716, allocations: 177.1 MB / 199.4 MB, free: 5.941 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001177/0.001177, allocations: 160.2 kB / 256 MB, free: 13.34 MB / 250.7 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo): time 0.2574/0.2574, allocations: 80.36 MB / 392.7 MB, free: 2.375 MB / 314.7 MB " [Timeout remaining time 179] Using package OpenIPSL with version 3.2.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo) Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo) Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo) Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo) Running command: "" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_TwoArea_FifthOrder_AVRII",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII,fileNamePrefix="OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_TwoArea_FifthOrder_AVRII",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.563e-06/1.563e-06, allocations: 0 / 0.5551 GB, free: 12.34 MB / 490.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 1.925e-05/2.081e-05, allocations: 5.516 kB / 0.5551 GB, free: 12.33 MB / 490.7 MB Notification: Performance of NFInst.instantiate(OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII): time 0.01224/0.01226, allocations: 18.56 MB / 0.5732 GB, free: 9.699 MB / 0.4948 GB Notification: Performance of NFInst.instExpressions: time 0.00889/0.02115, allocations: 4.865 MB / 0.578 GB, free: 4.816 MB / 0.4948 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.003167/0.02432, allocations: 48 kB / 0.578 GB, free: 4.77 MB / 0.4948 GB Notification: Performance of NFTyping.typeComponents: time 0.002696/0.02702, allocations: 0.8788 MB / 0.5789 GB, free: 3.883 MB / 0.4948 GB Notification: Performance of NFTyping.typeBindings: time 0.006911/0.03393, allocations: 2.363 MB / 0.5812 GB, free: 1.508 MB / 0.4948 GB Notification: Performance of NFTyping.typeClassSections: time 0.004287/0.03822, allocations: 2.092 MB / 0.5832 GB, free: 15.45 MB / 0.5105 GB Notification: Performance of NFFlatten.flatten: time 0.007042/0.04526, allocations: 5.924 MB / 0.589 GB, free: 9.496 MB / 0.5105 GB Notification: Performance of NFFlatten.resolveConnections: time 0.001627/0.04688, allocations: 0.8572 MB / 0.5898 GB, free: 8.586 MB / 0.5105 GB Notification: Performance of NFEvalConstants.evaluate: time 0.00522/0.0521, allocations: 3.339 MB / 0.5931 GB, free: 5.238 MB / 0.5105 GB Notification: Performance of NFSimplifyModel.simplify: time 0.003341/0.05545, allocations: 2.289 MB / 0.5953 GB, free: 2.941 MB / 0.5105 GB Notification: Performance of NFPackage.collectConstants: time 0.001449/0.0569, allocations: 444 kB / 0.5958 GB, free: 2.508 MB / 0.5105 GB Notification: Performance of NFFlatten.collectFunctions: time 0.001583/0.05848, allocations: 0.5974 MB / 0.5963 GB, free: 1.91 MB / 0.5105 GB Notification: Performance of NFScalarize.scalarize: time 0.0007199/0.0592, allocations: 1.186 MB / 0.5975 GB, free: 0.7188 MB / 0.5105 GB Notification: Performance of NFVerifyModel.verify: time 0.1959/0.2551, allocations: 1.734 MB / 0.5992 GB, free: 64.61 MB / 0.5105 GB Notification: Performance of NFConvertDAE.convert: time 0.005774/0.2609, allocations: 6.372 MB / 0.6054 GB, free: 64.2 MB / 0.5105 GB Notification: Performance of FrontEnd - DAE generated: time 4.869e-06/0.2609, allocations: 0.7031 kB / 0.6054 GB, free: 64.2 MB / 0.5105 GB Notification: Performance of FrontEnd: time 1.463e-06/0.2609, allocations: 0 / 0.6054 GB, free: 64.2 MB / 0.5105 GB Notification: Performance of Transformations before backend: time 0.0002437/0.2611, allocations: 0 / 0.6054 GB, free: 64.2 MB / 0.5105 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 894 * Number of variables: 894 Notification: Performance of Generate backend data structure: time 0.02004/0.2812, allocations: 9.91 MB / 0.6151 GB, free: 61.55 MB / 0.5105 GB Notification: Performance of prepare preOptimizeDAE: time 4.453e-05/0.2812, allocations: 10.05 kB / 0.6151 GB, free: 61.55 MB / 0.5105 GB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001051/0.2823, allocations: 490.1 kB / 0.6156 GB, free: 61.5 MB / 0.5105 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01447/0.2968, allocations: 4.053 MB / 0.6195 GB, free: 61.09 MB / 0.5105 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.005596/0.3023, allocations: 2.601 MB / 0.6221 GB, free: 60.24 MB / 0.5105 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007951/0.3031, allocations: 1.034 MB / 0.6231 GB, free: 59.45 MB / 0.5105 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.001544/0.3047, allocations: 350.1 kB / 0.6234 GB, free: 59.45 MB / 0.5105 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.009173/0.3139, allocations: 4.767 MB / 0.6281 GB, free: 57.92 MB / 0.5105 GB Notification: Performance of preOpt findStateOrder (simulation): time 4.808e-05/0.3139, allocations: 4.812 kB / 0.6281 GB, free: 57.92 MB / 0.5105 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003548/0.3143, allocations: 145.8 kB / 0.6282 GB, free: 57.81 MB / 0.5105 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.539e-05/0.3143, allocations: 84.59 kB / 0.6283 GB, free: 57.81 MB / 0.5105 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007958/0.3223, allocations: 4.255 MB / 0.6324 GB, free: 56.32 MB / 0.5105 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 preOpt removeSimpleEquations (simulation): time 0.02679/0.3491, allocations: 13.45 MB / 0.6456 GB, free: 48.46 MB / 0.5105 GB Notification: Performance of preOpt comSubExp (simulation): time 0.00713/0.3562, allocations: 4.38 MB / 0.6499 GB, free: 46.42 MB / 0.5105 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.004299/0.3605, allocations: 2.508 MB / 0.6523 GB, free: 45.13 MB / 0.5105 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0004625/0.361, allocations: 57.31 kB / 0.6524 GB, free: 45.11 MB / 0.5105 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004657/0.3656, allocations: 3.212 MB / 0.6555 GB, free: 43.25 MB / 0.5105 GB Notification: Performance of pre-optimization done (n=384): time 7.073e-06/0.3656, allocations: 0 / 0.6555 GB, free: 43.25 MB / 0.5105 GB Notification: Performance of matching and sorting (n=384): time 0.1479/0.5136, allocations: 67.55 MB / 0.7215 GB, free: 8.816 MB / 0.5417 GB Notification: Performance of inlineWhenForInitialization (initialization): time 8.429e-05/0.5137, allocations: 145.8 kB / 0.7216 GB, free: 8.648 MB / 0.5417 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003891/0.5175, allocations: 3.285 MB / 0.7248 GB, free: 5.344 MB / 0.5417 GB Notification: Performance of collectPreVariables (initialization): time 0.001001/0.5185, allocations: 89.69 kB / 0.7249 GB, free: 5.25 MB / 0.5417 GB Notification: Performance of collectInitialEqns (initialization): time 0.001058/0.5196, allocations: 2.006 MB / 0.7269 GB, free: 3.242 MB / 0.5417 GB Notification: Performance of collectInitialBindings (initialization): time 0.001977/0.5216, allocations: 1.367 MB / 0.7282 GB, free: 1.891 MB / 0.5417 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001094/0.5227, allocations: 0.6746 MB / 0.7288 GB, free: 1.203 MB / 0.5417 GB Notification: Performance of setup shared object (initialization): time 7.233e-05/0.5227, allocations: 305.1 kB / 0.7291 GB, free: 0.9023 MB / 0.5417 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.00377/0.5265, allocations: 2.653 MB / 0.7317 GB, free: 14.23 MB / 0.5573 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.004579/0.5311, allocations: 3.29 MB / 0.7349 GB, free: 10.71 MB / 0.5573 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.04085/0.5719, allocations: 19.21 MB / 0.7537 GB, free: 7.309 MB / 0.573 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.193e-05/0.572, allocations: 7.75 kB / 0.7537 GB, free: 7.305 MB / 0.573 GB Notification: Performance of matching and sorting (n=542) (initialization): time 0.03469/0.6067, allocations: 17.25 MB / 0.7706 GB, free: 6.195 MB / 0.5886 GB Notification: Performance of prepare postOptimizeDAE: time 6.796e-05/0.6067, allocations: 63.41 kB / 0.7706 GB, free: 6.121 MB / 0.5886 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.95e-05/0.6068, allocations: 12 kB / 0.7706 GB, free: 6.109 MB / 0.5886 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.0148/0.6216, allocations: 7.156 MB / 0.7776 GB, free: 14.95 MB / 0.6042 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002596/0.6242, allocations: 0.6869 MB / 0.7783 GB, free: 14.3 MB / 0.6042 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05432/0.6785, allocations: 32.39 MB / 0.8099 GB, free: 13.8 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004537/0.683, allocations: 0.6298 MB / 0.8105 GB, free: 13.16 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008859/0.6839, allocations: 156 kB / 0.8107 GB, free: 13.01 MB / 0.6355 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 order3.delta:VARIABLE(flow=false start = order3.delta0 unit = \"rad\" fixed = true ) \"Rotor angle\" type: Real order3.e1q:VARIABLE(start = order3.e1q0 unit = \"1\" fixed = true ) \"q-axis transient voltage\" type: Real order5.delta:VARIABLE(flow=false start = order5.delta0 unit = \"rad\" fixed = true ) \"Rotor angle\" 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: 2 ($whenCondition2,$whenCondition1) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (407): * Single equations (assignments): 404 * 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 {(92,44,5.8%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.002094/0.686, allocations: 0.6685 MB / 0.8113 GB, free: 12.38 MB / 0.6355 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00128/0.6873, allocations: 484.8 kB / 0.8118 GB, free: 11.9 MB / 0.6355 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03092/0.7182, allocations: 16.76 MB / 0.8282 GB, free: 11.18 MB / 0.6511 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.713e-05/0.7182, allocations: 39.56 kB / 0.8282 GB, free: 11.14 MB / 0.6511 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 3.673e-05/0.7183, allocations: 39.38 kB / 0.8282 GB, free: 11.11 MB / 0.6511 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.000103/0.7184, allocations: 19.92 kB / 0.8283 GB, free: 11.09 MB / 0.6511 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.04019/0.7585, allocations: 20.42 MB / 0.8482 GB, free: 6.75 MB / 0.6667 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.054e-05/0.7586, allocations: 4 kB / 0.8482 GB, free: 6.746 MB / 0.6667 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001637/0.7602, allocations: 390.4 kB / 0.8486 GB, free: 6.387 MB / 0.6667 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.2218/0.982, allocations: 7.366 MB / 0.8558 GB, free: 228.7 MB / 0.6667 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001196/0.9832, allocations: 78.42 kB / 0.8558 GB, free: 228.7 MB / 0.6667 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.05379/1.037, allocations: 30.77 MB / 0.8859 GB, free: 219.2 MB / 0.6667 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.559e-06/1.037, allocations: 3.5 kB / 0.8859 GB, free: 219.2 MB / 0.6667 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03552/1.073, allocations: 20.21 MB / 0.9056 GB, free: 214.1 MB / 0.6667 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001301/1.074, allocations: 0.5043 MB / 0.9061 GB, free: 213.8 MB / 0.6667 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001472/1.075, allocations: 63.62 kB / 0.9062 GB, free: 213.7 MB / 0.6667 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003232/1.079, allocations: 382 kB / 0.9065 GB, free: 213.7 MB / 0.6667 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002656/1.081, allocations: 0.8149 MB / 0.9073 GB, free: 213.5 MB / 0.6667 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.000487/1.082, allocations: 98.34 kB / 0.9074 GB, free: 213.5 MB / 0.6667 GB Notification: Performance of sorting global known variables: time 0.002185/1.084, allocations: 2.128 MB / 0.9095 GB, free: 212.7 MB / 0.6667 GB Notification: Performance of sort global known variables: time 2.61e-07/1.084, allocations: 3.969 kB / 0.9095 GB, free: 212.7 MB / 0.6667 GB Notification: Performance of remove unused functions: time 0.006124/1.09, allocations: 1.825 MB / 0.9113 GB, free: 211.6 MB / 0.6667 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,aVRTypeII.ExcitationSystem.x_scaled[1],aVRTypeII.derivativeBlock.x,aVRTypeII.firstOrder2.y,aVRTypeII.simpleLagLim.state) * Number of discrete variables: 2 ($whenCondition1,$whenCondition2) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (248): * Single equations (assignments): 245 * Array equations: 0 * Algorithm blocks: 1 * 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 {(100,36,5.5%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,6)} Notification: Performance of Backend phase and start with SimCode phase: time 0.03402/1.124, allocations: 20.86 MB / 0.9317 GB, free: 194.6 MB / 0.6667 GB Notification: Performance of simCode: created initialization part: time 0.01519/1.139, allocations: 8.857 MB / 0.9403 GB, free: 185.7 MB / 0.6667 GB Notification: Performance of simCode: created event and clocks part: time 4.268e-06/1.139, allocations: 0 / 0.9403 GB, free: 185.7 MB / 0.6667 GB Notification: Performance of simCode: created simulation system equations: time 0.0109/1.15, allocations: 5.912 MB / 0.9461 GB, free: 179.7 MB / 0.6667 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003387/1.153, allocations: 389.6 kB / 0.9465 GB, free: 179.3 MB / 0.6667 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.0935/1.247, allocations: 59.15 MB / 1.004 GB, free: 119.4 MB / 0.6667 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001417/1.248, allocations: 1.934 MB / 1.006 GB, free: 117.4 MB / 0.6667 GB Notification: Performance of simCode: alias equations: time 0.00127/1.25, allocations: 379.2 kB / 1.006 GB, free: 117.1 MB / 0.6667 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001538/1.251, allocations: 2.027 MB / 1.008 GB, free: 115 MB / 0.6667 GB Notification: Performance of SimCode: time 1.102e-06/1.251, allocations: 0 / 1.008 GB, free: 115 MB / 0.6667 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.3214/1.573, allocations: 138.1 MB / 1.143 GB, free: 219.7 MB / 0.6667 GB Notification: Performance of buildModelFMU: Generate platform static: time 12.89/14.46, allocations: 4.547 kB / 1.143 GB, free: 219.7 MB / 0.6667 GB " [Timeout remaining time 645] (rm -f OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.sim & python3 -m fmpy simulate --output-file OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII_res.csv --start-time 0 --stop-time 20 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.008 OpenIPSL_dev_OpenIPSL_Examples_PSATSystems_TwoArea_FifthOrder_AVRII.fmu > OpenIPSL_dev_OpenIPSL.Examples.PSATSystems.TwoArea.FifthOrder_AVRII.pipe 2>&1) [Timeout 52.5] [Calling sys.exit(0), Time elapsed: 16.96946080774069] Failed to read output from testmodel.py, exit status != 0: 14.59962553717196 14.608961856 14.346027818 Calling exit ...