Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IndustrialControlSystems_IndustrialControlSystems.Controllers.Examples.TestPI_bias.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.0008033/0.0008033, allocations: 80.44 kB / 19.17 MB, free: 0.7461 MB / 13.93 MB " [Timeout remaining time 180] 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.0008958/0.0008958, allocations: 165.9 kB / 22.46 MB, free: 2.109 MB / 18.57 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.8882/0.8882, allocations: 177.1 MB / 202.8 MB, free: 5.738 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IndustrialControlSystems 2.0.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IndustrialControlSystems 2.0.0/package.mo): time 0.03501/0.03501, allocations: 8.604 MB / 267.8 MB, free: 5.125 MB / 250.7 MB " [Timeout remaining time 180] Using package IndustrialControlSystems with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IndustrialControlSystems 2.0.0/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 Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 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(IndustrialControlSystems.Controllers.Examples.TestPI_bias,fileNamePrefix="IndustrialControlSystems_IndustrialControlSystems_Controllers_Examples_TestPI_bias",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(IndustrialControlSystems.Controllers.Examples.TestPI_bias,fileNamePrefix="IndustrialControlSystems_IndustrialControlSystems_Controllers_Examples_TestPI_bias",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 2.014e-06/2.014e-06, allocations: 0 / 395.1 MB, free: 2.672 MB / 362.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 1.989e-05/2.19e-05, allocations: 2.281 kB / 395.1 MB, free: 2.668 MB / 362.7 MB Notification: Performance of NFInst.instantiate(IndustrialControlSystems.Controllers.Examples.TestPI_bias): time 0.002604/0.002626, allocations: 2.154 MB / 397.2 MB, free: 0.5 MB / 362.7 MB Notification: Performance of NFInst.instExpressions: time 0.0009586/0.003584, allocations: 0.5698 MB / 397.8 MB, free: 15.93 MB / 378.7 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.0001624/0.003747, allocations: 15.81 kB / 397.8 MB, free: 15.91 MB / 378.7 MB Notification: Performance of NFTyping.typeComponents: time 0.0002063/0.003953, allocations: 71.31 kB / 397.9 MB, free: 15.84 MB / 378.7 MB Notification: Performance of NFTyping.typeBindings: time 8.597e-05/0.004039, allocations: 43.44 kB / 397.9 MB, free: 15.8 MB / 378.7 MB Notification: Performance of NFTyping.typeClassSections: time 0.0006685/0.004707, allocations: 347.2 kB / 398.3 MB, free: 15.46 MB / 378.7 MB Notification: Performance of NFFlatten.flatten: time 0.0004216/0.005129, allocations: 0.493 MB / 398.8 MB, free: 14.97 MB / 378.7 MB Notification: Performance of NFFlatten.resolveConnections: time 0.0001535/0.005283, allocations: 73.17 kB / 398.8 MB, free: 14.89 MB / 378.7 MB Notification: Performance of NFEvalConstants.evaluate: time 0.0001913/0.005474, allocations: 231.4 kB / 399.1 MB, free: 14.67 MB / 378.7 MB Notification: Performance of NFSimplifyModel.simplify: time 0.0001759/0.00565, allocations: 155.5 kB / 399.2 MB, free: 14.52 MB / 378.7 MB Notification: Performance of NFPackage.collectConstants: time 4.127e-05/0.005691, allocations: 35.98 kB / 399.3 MB, free: 14.48 MB / 378.7 MB Notification: Performance of NFFlatten.collectFunctions: time 3.14e-05/0.005722, allocations: 28 kB / 399.3 MB, free: 14.45 MB / 378.7 MB Notification: Performance of NFScalarize.scalarize: time 0.0001002/0.005823, allocations: 139.5 kB / 399.4 MB, free: 14.32 MB / 378.7 MB Notification: Performance of NFVerifyModel.verify: time 8.477e-05/0.005907, allocations: 115.6 kB / 399.5 MB, free: 14.2 MB / 378.7 MB Notification: Performance of NFConvertDAE.convert: time 0.0003427/0.00625, allocations: 418.5 kB / 399.9 MB, free: 13.79 MB / 378.7 MB Notification: Performance of FrontEnd - DAE generated: time 2.956e-06/0.006253, allocations: 0 / 399.9 MB, free: 13.79 MB / 378.7 MB Notification: Performance of FrontEnd: time 1.693e-06/0.006255, allocations: 0 / 399.9 MB, free: 13.79 MB / 378.7 MB Notification: Performance of Transformations before backend: time 6.903e-06/0.006262, allocations: 0 / 399.9 MB, free: 13.79 MB / 378.7 MB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 100 * Number of variables: 100 Notification: Performance of Generate backend data structure: time 0.001108/0.00737, allocations: 0.6843 MB / 400.6 MB, free: 13.07 MB / 378.7 MB Notification: Performance of prepare preOptimizeDAE: time 6.15e-05/0.007432, allocations: 16.03 kB / 400.6 MB, free: 13.05 MB / 378.7 MB Notification: Performance of preOpt introduceOutputAliases (simulation): time 8.694e-05/0.007519, allocations: 78.7 kB / 400.7 MB, free: 12.97 MB / 378.7 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 8.396e-05/0.007603, allocations: 56.56 kB / 400.8 MB, free: 12.91 MB / 378.7 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0004875/0.00809, allocations: 343.9 kB / 401.1 MB, free: 12.55 MB / 378.7 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.146e-05/0.008122, allocations: 29.38 kB / 401.1 MB, free: 12.52 MB / 378.7 MB Notification: Performance of preOpt expandDerOperator (simulation): time 6.128e-05/0.008183, allocations: 48.36 kB / 401.2 MB, free: 12.47 MB / 378.7 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0005362/0.008719, allocations: 492.5 kB / 401.7 MB, free: 11.96 MB / 378.7 MB Notification: Performance of preOpt findStateOrder (simulation): time 1.854e-05/0.008738, allocations: 8.141 kB / 401.7 MB, free: 11.95 MB / 378.7 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 2.863e-05/0.008766, allocations: 16.38 kB / 401.7 MB, free: 11.93 MB / 378.7 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.346e-05/0.00878, allocations: 12.12 kB / 401.7 MB, free: 11.92 MB / 378.7 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0003878/0.009168, allocations: 347.6 kB / 402 MB, free: 11.58 MB / 378.7 MB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.001752/0.01092, allocations: 1.827 MB / 403.9 MB, free: 9.668 MB / 378.7 MB Notification: Performance of preOpt comSubExp (simulation): time 0.000491/0.01141, allocations: 222.2 kB / 404.1 MB, free: 9.445 MB / 378.7 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.0001335/0.01154, allocations: 116.7 kB / 404.2 MB, free: 9.328 MB / 378.7 MB Notification: Performance of preOpt evalFunc (simulation): time 2.784e-05/0.01157, allocations: 4 kB / 404.2 MB, free: 9.324 MB / 378.7 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.071e-05/0.01159, allocations: 38.56 kB / 404.2 MB, free: 9.277 MB / 378.7 MB Notification: Performance of pre-optimization done (n=25): time 1.693e-06/0.01159, allocations: 0 / 404.2 MB, free: 9.277 MB / 378.7 MB Notification: Performance of matching and sorting (n=25): time 0.0005147/0.01211, allocations: 365.5 kB / 404.6 MB, free: 8.914 MB / 378.7 MB Notification: Performance of inlineWhenForInitialization (initialization): time 3.727e-05/0.01215, allocations: 55.41 kB / 404.6 MB, free: 8.848 MB / 378.7 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.000313/0.01246, allocations: 441.7 kB / 405.1 MB, free: 8.406 MB / 378.7 MB Notification: Performance of collectPreVariables (initialization): time 1.845e-05/0.01248, allocations: 33.7 kB / 405.1 MB, free: 8.367 MB / 378.7 MB Notification: Performance of collectInitialEqns (initialization): time 0.0001346/0.01261, allocations: 280 kB / 405.4 MB, free: 8.09 MB / 378.7 MB Notification: Performance of collectInitialBindings (initialization): time 5.426e-05/0.01267, allocations: 107.1 kB / 405.5 MB, free: 7.984 MB / 378.7 MB Notification: Performance of simplifyInitialFunctions (initialization): time 9.285e-05/0.01276, allocations: 86.14 kB / 405.6 MB, free: 7.895 MB / 378.7 MB Notification: Performance of setup shared object (initialization): time 9.816e-05/0.01286, allocations: 358.8 kB / 405.9 MB, free: 7.535 MB / 378.7 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001318/0.01299, allocations: 112.9 kB / 406 MB, free: 7.422 MB / 378.7 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0001905/0.01318, allocations: 277.7 kB / 406.3 MB, free: 7.074 MB / 378.7 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.001123/0.0143, allocations: 0.7453 MB / 407.1 MB, free: 6.258 MB / 378.7 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.757e-06/0.01431, allocations: 3.938 kB / 407.1 MB, free: 6.254 MB / 378.7 MB Notification: Performance of matching and sorting (n=59) (initialization): time 0.0004995/0.01481, allocations: 0.4926 MB / 407.5 MB, free: 5.754 MB / 378.7 MB Notification: Performance of prepare postOptimizeDAE: time 1.671e-05/0.01482, allocations: 8.719 kB / 407.6 MB, free: 5.746 MB / 378.7 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.234e-06/0.01483, allocations: 4 kB / 407.6 MB, free: 5.742 MB / 378.7 MB Notification: Performance of postOpt tearingSystem (initialization): time 1.535e-05/0.01485, allocations: 16 kB / 407.6 MB, free: 5.727 MB / 378.7 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0002183/0.01506, allocations: 111.8 kB / 407.7 MB, free: 5.617 MB / 378.7 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 9.237e-06/0.01507, allocations: 11.98 kB / 407.7 MB, free: 5.605 MB / 378.7 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0001179/0.01519, allocations: 67.5 kB / 407.8 MB, free: 5.539 MB / 378.7 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 1.858e-05/0.01521, allocations: 20 kB / 407.8 MB, free: 5.52 MB / 378.7 MB Warning: Assuming fixed start value for the following 9 variables: ProcessBias.w:VARIABLE(fixed = true protected = true ) \"proper part of the output\" type: Real ProcessBias.U[2]:VARIABLE(fixed = true protected = true ) type: Real [2] ProcessBias.Y[3]:VARIABLE(fixed = true protected = true ) type: Real [3] processNoCtrl.w:VARIABLE(fixed = true protected = true ) \"proper part of the output\" type: Real processNoCtrl.U[2]:VARIABLE(fixed = true protected = true ) type: Real [2] processNoCtrl.Y[3]:VARIABLE(fixed = true protected = true ) type: Real [3] ProcessCtrl.w:VARIABLE(fixed = true protected = true ) \"proper part of the output\" type: Real ProcessCtrl.U[2]:VARIABLE(fixed = true protected = true ) type: Real [2] ProcessCtrl.Y[3]:VARIABLE(fixed = true protected = true ) type: Real [3] Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 24 * Number of states: 0 () * Number of discrete variables: 8 (R_Bias.TS_in,R.TS_in,R_Bias.satLOW_in,R_Bias.satHIGH_in,R_Bias.ATreq_in,R.satLOW_in,R.satHIGH_in,R.ATreq_in) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (59): * Single equations (assignments): 59 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Performance of prepare postOptimizeDAE: time 0.0001808/0.01539, allocations: 207.5 kB / 408 MB, free: 5.309 MB / 378.7 MB Notification: Performance of postOpt lateInlineFunction (simulation): time 3.74e-05/0.01543, allocations: 43.72 kB / 408 MB, free: 5.266 MB / 378.7 MB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0003985/0.01583, allocations: 353.5 kB / 408.4 MB, free: 4.914 MB / 378.7 MB Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.878e-06/0.01583, allocations: 4 kB / 408.4 MB, free: 4.91 MB / 378.7 MB Notification: Performance of postOpt constantLinearSystem (simulation): time 3.657e-06/0.01583, allocations: 0 / 408.4 MB, free: 4.91 MB / 378.7 MB Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.558e-06/0.01584, allocations: 3.938 kB / 408.4 MB, free: 4.906 MB / 378.7 MB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0002969/0.01614, allocations: 360.4 kB / 408.7 MB, free: 4.512 MB / 378.7 MB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.753e-06/0.01614, allocations: 0 / 408.7 MB, free: 4.512 MB / 378.7 MB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0002163/0.01635, allocations: 83.88 kB / 408.8 MB, free: 4.43 MB / 378.7 MB Notification: Performance of postOpt tearingSystem (simulation): time 2.264e-06/0.01636, allocations: 0 / 408.8 MB, free: 4.43 MB / 378.7 MB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 1.722e-05/0.01637, allocations: 8 kB / 408.8 MB, free: 4.422 MB / 378.7 MB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.543e-06/0.01638, allocations: 0 / 408.8 MB, free: 4.422 MB / 378.7 MB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.603e-06/0.01638, allocations: 8 kB / 408.8 MB, free: 4.414 MB / 378.7 MB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0005084/0.01689, allocations: 0.5312 MB / 409.4 MB, free: 3.863 MB / 378.7 MB Notification: Performance of postOpt removeConstants (simulation): time 3.845e-05/0.01692, allocations: 43.66 kB / 409.4 MB, free: 3.82 MB / 378.7 MB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 3.16e-05/0.01696, allocations: 15.98 kB / 409.4 MB, free: 3.805 MB / 378.7 MB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 7.643e-05/0.01703, allocations: 11.98 kB / 409.4 MB, free: 3.793 MB / 378.7 MB Notification: Performance of postOpt findZeroCrossings (simulation): time 4.687e-05/0.01708, allocations: 19.97 kB / 409.5 MB, free: 3.773 MB / 378.7 MB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 9.658e-06/0.01709, allocations: 8.047 kB / 409.5 MB, free: 3.766 MB / 378.7 MB Notification: Performance of sorting global known variables: time 0.0002078/0.0173, allocations: 303 kB / 409.8 MB, free: 3.469 MB / 378.7 MB Notification: Performance of sort global known variables: time 7e-08/0.0173, allocations: 4 kB / 409.8 MB, free: 3.465 MB / 378.7 MB Notification: Performance of remove unused functions: time 0.000248/0.01754, allocations: 71.89 kB / 409.8 MB, free: 3.395 MB / 378.7 MB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 1 * Number of states: 11 (R.FBout,R_Bias.FBout,ProcessCtrl.Y[3],ProcessCtrl.U[2],ProcessCtrl.w,processNoCtrl.Y[3],processNoCtrl.U[2],processNoCtrl.w,ProcessBias.Y[3],ProcessBias.U[2],ProcessBias.w) * 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 (29): * Single equations (assignments): 29 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Performance of Backend phase and start with SimCode phase: time 0.0007095/0.01825, allocations: 1.08 MB / 410.9 MB, free: 2.289 MB / 378.7 MB Notification: Performance of simCode: created initialization part: time 0.0003995/0.01865, allocations: 312.3 kB / 411.2 MB, free: 1.984 MB / 378.7 MB Notification: Performance of simCode: created event and clocks part: time 1.924e-06/0.01865, allocations: 0 / 411.2 MB, free: 1.984 MB / 378.7 MB Notification: Performance of simCode: created simulation system equations: time 0.0001338/0.01879, allocations: 131.8 kB / 411.3 MB, free: 1.855 MB / 378.7 MB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.000257/0.01905, allocations: 41.58 kB / 411.4 MB, free: 1.816 MB / 378.7 MB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003227/0.02227, allocations: 3.939 MB / 415.3 MB, free: 13.65 MB / 394.7 MB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0001674/0.02244, allocations: 250 kB / 415.6 MB, free: 13.38 MB / 394.7 MB Notification: Performance of simCode: alias equations: time 0.0001411/0.02258, allocations: 79.3 kB / 415.6 MB, free: 13.3 MB / 394.7 MB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001461/0.02273, allocations: 164.4 kB / 415.8 MB, free: 13.14 MB / 394.7 MB Notification: Performance of SimCode: time 5.31e-07/0.02273, allocations: 4 kB / 415.8 MB, free: 13.14 MB / 394.7 MB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.01942/0.04215, allocations: 7.634 MB / 423.4 MB, free: 5.652 MB / 394.7 MB Notification: Performance of buildModelFMU: Generate platform static: time 7.082/7.124, allocations: 12.38 kB / 423.5 MB, free: 5.645 MB / 394.7 MB " [Timeout remaining time 653] (rm -f IndustrialControlSystems_IndustrialControlSystems.Controllers.Examples.TestPI_bias.pipe ; mkfifo IndustrialControlSystems_IndustrialControlSystems.Controllers.Examples.TestPI_bias.pipe ; head -c 1048576 < IndustrialControlSystems_IndustrialControlSystems.Controllers.Examples.TestPI_bias.pipe >> ../files/IndustrialControlSystems_IndustrialControlSystems.Controllers.Examples.TestPI_bias.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --tempDir=temp_IndustrialControlSystems_IndustrialControlSystems_Controllers_Examples_TestPI_bias_fmu --startTime=0 --stopTime=100 --stepSize=0.04 --timeout=50 --tolerance=1e-06 IndustrialControlSystems_IndustrialControlSystems_Controllers_Examples_TestPI_bias.fmu > IndustrialControlSystems_IndustrialControlSystems.Controllers.Examples.TestPI_bias.pipe 2>&1) [Timeout 52.5] [Calling sys.exit(0), Time elapsed: 8.926570604555309] Failed to read output from testmodel.py, exit status != 0: 7.214758235029876 7.227029712 7.218532398 Calling exit ...