Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test.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.001691/0.001691, allocations: 93.06 kB / 19.65 MB, free: 276 kB / 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.9596/0.9596, allocations: 177.2 MB / 200 MB, free: 5.547 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.001265/0.001265, allocations: 163.5 kB / 256.6 MB, free: 12.99 MB / 250.7 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo): time 0.2923/0.2923, allocations: 80.24 MB / 393.4 MB, free: 2.164 MB / 314.7 MB " [Timeout remaining time 179] Using package OpenIPSL with version 3.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/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: translateModel(OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test") translateModel(OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.904e-06/1.904e-06, allocations: 0 / 489.8 MB, free: 11.58 MB / 410.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 2.837e-05/3.028e-05, allocations: 4.281 kB / 489.8 MB, free: 11.58 MB / 410.7 MB Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test): time 0.007611/0.007641, allocations: 6.1 MB / 495.9 MB, free: 5.457 MB / 410.7 MB Notification: Performance of NFInst.instExpressions: time 0.003194/0.01083, allocations: 1.952 MB / 497.9 MB, free: 3.516 MB / 410.7 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.0004688/0.0113, allocations: 15.94 kB / 497.9 MB, free: 3.5 MB / 410.7 MB Notification: Performance of NFTyping.typeComponents: time 0.0006348/0.01194, allocations: 231 kB / 498.1 MB, free: 3.273 MB / 410.7 MB Notification: Performance of NFTyping.typeBindings: time 0.001411/0.01335, allocations: 0.7899 MB / 498.9 MB, free: 2.484 MB / 410.7 MB Notification: Performance of NFTyping.typeClassSections: time 0.001042/0.01439, allocations: 0.6728 MB / 499.6 MB, free: 1.824 MB / 410.7 MB Notification: Performance of NFFlatten.flatten: time 0.0009628/0.01536, allocations: 1.471 MB / 0.4893 GB, free: 356 kB / 410.7 MB Notification: Performance of NFFlatten.resolveConnections: time 0.000274/0.01563, allocations: 208.9 kB / 0.4895 GB, free: 132 kB / 410.7 MB Notification: Performance of NFEvalConstants.evaluate: time 0.0006275/0.01626, allocations: 0.8433 MB / 0.4903 GB, free: 15.29 MB / 426.7 MB Notification: Performance of NFSimplifyModel.simplify: time 0.0004812/0.01674, allocations: 0.588 MB / 0.4909 GB, free: 14.7 MB / 426.7 MB Notification: Performance of NFPackage.collectConstants: time 0.000104/0.01684, allocations: 104 kB / 0.491 GB, free: 14.59 MB / 426.7 MB Notification: Performance of NFFlatten.collectFunctions: time 0.0003232/0.01716, allocations: 251.8 kB / 0.4912 GB, free: 14.35 MB / 426.7 MB Notification: Performance of NFScalarize.scalarize: time 0.0001763/0.01734, allocations: 299.9 kB / 0.4915 GB, free: 14.05 MB / 426.7 MB Notification: Performance of NFVerifyModel.verify: time 0.0002631/0.0176, allocations: 418.6 kB / 0.4919 GB, free: 13.64 MB / 426.7 MB Notification: Performance of NFConvertDAE.convert: time 0.0008887/0.01849, allocations: 1.152 MB / 0.4931 GB, free: 12.49 MB / 426.7 MB Notification: Performance of FrontEnd - DAE generated: time 4.017e-06/0.0185, allocations: 1.562 kB / 0.4931 GB, free: 12.49 MB / 426.7 MB Notification: Performance of FrontEnd: time 1.443e-06/0.0185, allocations: 0 / 0.4931 GB, free: 12.49 MB / 426.7 MB Notification: Performance of Transformations before backend: time 1.001e-05/0.01851, allocations: 0 / 0.4931 GB, free: 12.49 MB / 426.7 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 190 * Number of variables: 190 Notification: Performance of Generate backend data structure: time 0.00468/0.02319, allocations: 2.623 MB / 0.4956 GB, free: 9.855 MB / 426.7 MB Notification: Performance of prepare preOptimizeDAE: time 4.545e-05/0.02323, allocations: 13.03 kB / 0.4956 GB, free: 9.844 MB / 426.7 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004386/0.02762, allocations: 0.9216 MB / 0.4965 GB, free: 8.926 MB / 426.7 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.001204/0.02882, allocations: 0.7509 MB / 0.4973 GB, free: 8.129 MB / 426.7 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001809/0.029, allocations: 214.4 kB / 0.4975 GB, free: 7.887 MB / 426.7 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001513/0.02916, allocations: 83.98 kB / 0.4975 GB, free: 7.805 MB / 426.7 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.001456/0.03061, allocations: 0.7617 MB / 0.4983 GB, free: 7.023 MB / 426.7 MB Notification: Performance of preOpt findStateOrder (simulation): time 1.779e-05/0.03063, allocations: 0 / 0.4983 GB, free: 7.023 MB / 426.7 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.886e-05/0.03072, allocations: 36 kB / 0.4983 GB, free: 6.988 MB / 426.7 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.154e-05/0.03074, allocations: 23.92 kB / 0.4983 GB, free: 6.965 MB / 426.7 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001261/0.032, allocations: 487.6 kB / 0.4988 GB, free: 6.484 MB / 426.7 MB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.004258/0.03626, allocations: 2.173 MB / 0.5009 GB, free: 4.277 MB / 426.7 MB Notification: Performance of preOpt comSubExp (simulation): time 0.001615/0.03787, allocations: 0.6052 MB / 0.5015 GB, free: 3.66 MB / 426.7 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.0007103/0.03858, allocations: 339.2 kB / 0.5019 GB, free: 3.32 MB / 426.7 MB Notification: Performance of preOpt evalFunc (simulation): time 0.0001082/0.03869, allocations: 20.98 kB / 0.5019 GB, free: 3.301 MB / 426.7 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.62e-05/0.03873, allocations: 46.39 kB / 0.5019 GB, free: 3.246 MB / 426.7 MB Notification: Performance of pre-optimization done (n=93): time 2.935e-06/0.03873, allocations: 4 kB / 0.5019 GB, free: 3.242 MB / 426.7 MB Notification: Performance of matching and sorting (n=93): time 0.01629/0.05502, allocations: 4.858 MB / 0.5067 GB, free: 14.36 MB / 442.7 MB Notification: Performance of inlineWhenForInitialization (initialization): time 4.446e-05/0.05506, allocations: 94.11 kB / 0.5068 GB, free: 14.25 MB / 442.7 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0007138/0.05578, allocations: 0.8689 MB / 0.5076 GB, free: 13.36 MB / 442.7 MB Notification: Performance of collectPreVariables (initialization): time 6.105e-05/0.05584, allocations: 45.69 kB / 0.5076 GB, free: 13.31 MB / 442.7 MB Notification: Performance of collectInitialEqns (initialization): time 0.0002797/0.05612, allocations: 0.5389 MB / 0.5082 GB, free: 12.77 MB / 442.7 MB Notification: Performance of collectInitialBindings (initialization): time 0.0002071/0.05632, allocations: 349 kB / 0.5085 GB, free: 12.42 MB / 442.7 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002608/0.05658, allocations: 200.6 kB / 0.5087 GB, free: 12.22 MB / 442.7 MB Notification: Performance of setup shared object (initialization): time 0.000145/0.05673, allocations: 0.5276 MB / 0.5092 GB, free: 11.68 MB / 442.7 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004848/0.05721, allocations: 320.8 kB / 0.5095 GB, free: 11.37 MB / 442.7 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005542/0.05777, allocations: 496.7 kB / 0.51 GB, free: 10.84 MB / 442.7 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.001481/0.05925, allocations: 1.191 MB / 0.5112 GB, free: 9.605 MB / 442.7 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.497e-06/0.05925, allocations: 0 / 0.5112 GB, free: 9.605 MB / 442.7 MB Notification: Performance of matching and sorting (n=142) (initialization): time 0.005459/0.06471, allocations: 2.918 MB / 0.514 GB, free: 6.672 MB / 442.7 MB Notification: Performance of prepare postOptimizeDAE: time 2.021e-05/0.06473, allocations: 8 kB / 0.514 GB, free: 6.664 MB / 442.7 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.136e-06/0.06474, allocations: 0 / 0.514 GB, free: 6.664 MB / 442.7 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.005059/0.0698, allocations: 1.989 MB / 0.516 GB, free: 4.668 MB / 442.7 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004035/0.0702, allocations: 171.7 kB / 0.5161 GB, free: 4.5 MB / 442.7 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007959/0.07816, allocations: 5.113 MB / 0.5211 GB, free: 15.26 MB / 458.7 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005683/0.07873, allocations: 143.3 kB / 0.5212 GB, free: 15.12 MB / 458.7 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.966e-05/0.07879, allocations: 35.98 kB / 0.5213 GB, free: 15.08 MB / 458.7 MB Warning: Assuming fixed start value for the following 1 variables: tGtypeII.transferFunction1.x_scaled[1]:VARIABLE(fixed = true protected = true ) \"Scaled vector x\" type: Real [1] Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0006306/0.07942, allocations: 466.3 kB / 0.5217 GB, free: 14.63 MB / 458.7 MB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0005392/0.07996, allocations: 492.8 kB / 0.5222 GB, free: 14.1 MB / 458.7 MB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.001441/0.0814, allocations: 1.195 MB / 0.5234 GB, free: 12.87 MB / 458.7 MB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.525e-06/0.0814, allocations: 0 / 0.5234 GB, free: 12.87 MB / 458.7 MB Notification: Performance of matching and sorting (n=142) (initialization_lambda0): time 0.005456/0.08686, allocations: 2.922 MB / 0.5262 GB, free: 9.93 MB / 458.7 MB Notification: Performance of prepare postOptimizeDAE: time 1.214e-05/0.08687, allocations: 8 kB / 0.5262 GB, free: 9.922 MB / 458.7 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.509e-06/0.08688, allocations: 0 / 0.5262 GB, free: 9.922 MB / 458.7 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.004925/0.0918, allocations: 1.985 MB / 0.5282 GB, free: 7.93 MB / 458.7 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0003788/0.09218, allocations: 163.7 kB / 0.5283 GB, free: 7.77 MB / 458.7 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007667/0.09985, allocations: 5.129 MB / 0.5333 GB, free: 2.516 MB / 458.7 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005515/0.1004, allocations: 135.4 kB / 0.5335 GB, free: 2.383 MB / 458.7 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.742e-05/0.1005, allocations: 35.98 kB / 0.5335 GB, free: 2.348 MB / 458.7 MB Warning: Assuming fixed start value for the following 1 variables: tGtypeII.transferFunction1.x_scaled[1]:VARIABLE(fixed = true protected = true ) \"Scaled vector x\" type: Real [1] Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 9 * 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 (102): * Single equations (assignments): 101 * 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,16)} Notification: Performance of prepare postOptimizeDAE: time 0.000226/0.1007, allocations: 323.8 kB / 0.5338 GB, free: 2.023 MB / 458.7 MB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000164/0.1008, allocations: 151.2 kB / 0.5339 GB, free: 1.875 MB / 458.7 MB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005003/0.1058, allocations: 2.682 MB / 0.5366 GB, free: 15.18 MB / 474.7 MB Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.623e-06/0.1059, allocations: 15.94 kB / 0.5366 GB, free: 15.17 MB / 474.7 MB Notification: Performance of postOpt constantLinearSystem (simulation): time 2.214e-06/0.1059, allocations: 7.938 kB / 0.5366 GB, free: 15.16 MB / 474.7 MB Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.141e-06/0.1059, allocations: 3.984 kB / 0.5366 GB, free: 15.16 MB / 474.7 MB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.006012/0.1119, allocations: 3.598 MB / 0.5401 GB, free: 11.51 MB / 474.7 MB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.176e-06/0.1119, allocations: 4 kB / 0.5401 GB, free: 11.5 MB / 474.7 MB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0002543/0.1121, allocations: 87.89 kB / 0.5402 GB, free: 11.42 MB / 474.7 MB Notification: Performance of postOpt tearingSystem (simulation): time 0.004855/0.117, allocations: 1.908 MB / 0.5421 GB, free: 9.504 MB / 474.7 MB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.122e-05/0.117, allocations: 20 kB / 0.5421 GB, free: 9.484 MB / 474.7 MB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.006768/0.1238, allocations: 4.54 MB / 0.5465 GB, free: 4.816 MB / 474.7 MB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 9.22e-07/0.1238, allocations: 4 kB / 0.5465 GB, free: 4.812 MB / 474.7 MB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.005493/0.1293, allocations: 3.09 MB / 0.5495 GB, free: 1.695 MB / 474.7 MB Notification: Performance of postOpt removeConstants (simulation): time 0.0001325/0.1294, allocations: 131 kB / 0.5497 GB, free: 1.566 MB / 474.7 MB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 8.971e-05/0.1295, allocations: 16 kB / 0.5497 GB, free: 1.551 MB / 474.7 MB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004236/0.13, allocations: 79.92 kB / 0.5497 GB, free: 1.473 MB / 474.7 MB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003176/0.1303, allocations: 196.6 kB / 0.5499 GB, free: 1.281 MB / 474.7 MB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.42e-05/0.1303, allocations: 27.92 kB / 0.55 GB, free: 1.254 MB / 474.7 MB Notification: Performance of sorting global known variables: time 0.0004339/0.1308, allocations: 0.5487 MB / 0.5505 GB, free: 0.6953 MB / 474.7 MB Notification: Performance of sort global known variables: time 1.5e-07/0.1308, allocations: 0 / 0.5505 GB, free: 0.6953 MB / 474.7 MB Notification: Performance of remove unused functions: time 0.001272/0.132, allocations: 0.726 MB / 0.5512 GB, free: 15.96 MB / 490.7 MB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 1 * Number of states: 4 (gen.delta,gen.w,gen.e1q,tGtypeII.transferFunction1.x_scaled[1]) * 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 (56): * Single equations (assignments): 55 * 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,14)} Notification: Performance of Backend phase and start with SimCode phase: time 0.000213/0.1322, allocations: 135.1 kB / 0.5513 GB, free: 15.82 MB / 490.7 MB Notification: Performance of simCode: created initialization part: time 0.004719/0.137, allocations: 2.988 MB / 0.5543 GB, free: 12.79 MB / 490.7 MB Notification: Performance of simCode: created event and clocks part: time 1.953e-06/0.137, allocations: 4.062 kB / 0.5543 GB, free: 12.79 MB / 490.7 MB Notification: Performance of simCode: created simulation system equations: time 0.001743/0.1387, allocations: 1.179 MB / 0.5554 GB, free: 11.58 MB / 490.7 MB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0004592/0.1392, allocations: 110.9 kB / 0.5555 GB, free: 11.48 MB / 490.7 MB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00255/0.1417, allocations: 1.921 MB / 0.5574 GB, free: 9.523 MB / 490.7 MB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0001578/0.1419, allocations: 250.2 kB / 0.5576 GB, free: 9.258 MB / 490.7 MB Notification: Performance of simCode: alias equations: time 0.0002284/0.1421, allocations: 115 kB / 0.5577 GB, free: 9.141 MB / 490.7 MB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001455/0.1422, allocations: 68.48 kB / 0.5578 GB, free: 9.074 MB / 490.7 MB Notification: Performance of SimCode: time 7.21e-07/0.1422, allocations: 0 / 0.5578 GB, free: 9.074 MB / 490.7 MB Notification: Performance of Templates: time 0.03287/0.1751, allocations: 36.34 MB / 0.5933 GB, free: 4.809 MB / 0.5105 GB " [Timeout remaining time 660] make -j1 -f OpenIPSL_OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test.makefile [Timeout 660] (rm -f OpenIPSL_OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test.pipe ; mkfifo OpenIPSL_OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test.pipe >> ../files/OpenIPSL_OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test.sim & ./OpenIPSL_OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test -abortSlowSimulation -alarm=240 -lv LOG_STATS > OpenIPSL_OpenIPSL.Tests.Controls.PSAT.TG.TGTypeII_test.pipe 2>&1) [Timeout 240] [Calling sys.exit(0), Time elapsed: 4.424031272996217]