Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T.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.0008104/0.0008104, allocations: 84.44 kB / 20.69 MB, free: 3.871 MB / 18.57 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.0007848/0.0007848, allocations: 165.9 kB / 23.96 MB, free: 0.5977 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.897/0.897, allocations: 177.1 MB / 204.3 MB, free: 5.719 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo): time 0.5685/0.5685, allocations: 114 MB / 374.7 MB, free: 8.707 MB / 346.7 MB " [Timeout remaining time 179] Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/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: translateModel(IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T,tolerance=1e-08,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T") translateModel(IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T,tolerance=1e-08,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.202e-06/1.202e-06, allocations: 0 / 0.5554 GB, free: 2.238 MB / 0.5105 GB Notification: Performance of FrontEnd - Absyn->SCode: time 2.604e-05/2.724e-05, allocations: 11.66 kB / 0.5554 GB, free: 2.23 MB / 0.5105 GB Notification: Performance of NFInst.instantiate(IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T): time 0.03307/0.0331, allocations: 49.19 MB / 0.6035 GB, free: 0.8711 MB / 0.5573 GB Notification: Performance of NFInst.instExpressions: time 0.02034/0.05344, allocations: 15.9 MB / 0.619 GB, free: 0.9258 MB / 0.573 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.002274/0.05571, allocations: 123 kB / 0.6191 GB, free: 0.8047 MB / 0.573 GB Notification: Performance of NFTyping.typeComponents: time 0.003843/0.05955, allocations: 1.96 MB / 0.621 GB, free: 14.84 MB / 0.5886 GB Notification: Performance of NFTyping.typeBindings: time 0.004549/0.0641, allocations: 2.75 MB / 0.6237 GB, free: 12.08 MB / 0.5886 GB Notification: Performance of NFTyping.typeClassSections: time 0.004175/0.06828, allocations: 2.501 MB / 0.6262 GB, free: 9.602 MB / 0.5886 GB Notification: Performance of NFFlatten.flatten: time 0.007316/0.07559, allocations: 8.382 MB / 0.6343 GB, free: 1.199 MB / 0.5886 GB Notification: Performance of NFFlatten.resolveConnections: time 0.001351/0.07694, allocations: 1.317 MB / 0.6356 GB, free: 15.84 MB / 0.6042 GB Notification: Performance of NFEvalConstants.evaluate: time 0.003573/0.08052, allocations: 3.096 MB / 0.6387 GB, free: 12.75 MB / 0.6042 GB Notification: Performance of NFSimplifyModel.simplify: time 0.001399/0.08192, allocations: 1.227 MB / 0.6399 GB, free: 11.52 MB / 0.6042 GB Notification: Performance of NFPackage.collectConstants: time 0.0001643/0.08208, allocations: 168 kB / 0.64 GB, free: 11.36 MB / 0.6042 GB Notification: Performance of NFFlatten.collectFunctions: time 0.001991/0.08407, allocations: 1.43 MB / 0.6414 GB, free: 9.926 MB / 0.6042 GB Notification: Performance of NFScalarize.scalarize: time 0.0003881/0.08446, allocations: 0.685 MB / 0.6421 GB, free: 9.238 MB / 0.6042 GB Notification: Performance of NFVerifyModel.verify: time 0.000567/0.08503, allocations: 0.7597 MB / 0.6428 GB, free: 8.477 MB / 0.6042 GB Notification: Performance of NFConvertDAE.convert: time 0.003318/0.08834, allocations: 3.688 MB / 0.6464 GB, free: 4.777 MB / 0.6042 GB Notification: Performance of FrontEnd - DAE generated: time 4.408e-06/0.08835, allocations: 0 / 0.6464 GB, free: 4.777 MB / 0.6042 GB Notification: Performance of FrontEnd: time 1.823e-06/0.08835, allocations: 4 kB / 0.6464 GB, free: 4.773 MB / 0.6042 GB Notification: Performance of Transformations before backend: time 2.17e-05/0.08837, allocations: 0 / 0.6464 GB, free: 4.773 MB / 0.6042 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 282 * Number of variables: 282 Notification: Performance of Generate backend data structure: time 0.00295/0.09132, allocations: 2.877 MB / 0.6492 GB, free: 1.828 MB / 0.6042 GB Notification: Performance of prepare preOptimizeDAE: time 3.883e-05/0.09136, allocations: 8.625 kB / 0.6492 GB, free: 1.82 MB / 0.6042 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01168/0.103, allocations: 4.353 MB / 0.6535 GB, free: 13.46 MB / 0.6198 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.003307/0.1063, allocations: 2.867 MB / 0.6563 GB, free: 10.51 MB / 0.6198 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.469e-05/0.1064, allocations: 86.22 kB / 0.6564 GB, free: 10.43 MB / 0.6198 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001558/0.1066, allocations: 120 kB / 0.6565 GB, free: 10.31 MB / 0.6198 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.004905/0.1115, allocations: 2.922 MB / 0.6593 GB, free: 7.25 MB / 0.6198 GB Notification: Performance of preOpt findStateOrder (simulation): time 2.521e-05/0.1115, allocations: 0 / 0.6593 GB, free: 7.25 MB / 0.6198 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001009/0.1116, allocations: 48 kB / 0.6594 GB, free: 7.203 MB / 0.6198 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.737e-05/0.1116, allocations: 28 kB / 0.6594 GB, free: 7.176 MB / 0.6198 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008367/0.12, allocations: 3.809 MB / 0.6631 GB, free: 3.355 MB / 0.6198 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.01493/0.1349, allocations: 9.691 MB / 0.6726 GB, free: 9.004 MB / 0.6355 GB Notification: Performance of preOpt comSubExp (simulation): time 0.004845/0.1398, allocations: 2.278 MB / 0.6748 GB, free: 6.715 MB / 0.6355 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.003737/0.1435, allocations: 1.783 MB / 0.6766 GB, free: 4.926 MB / 0.6355 GB Notification: Performance of preOpt evalFunc (simulation): time 0.003991/0.1475, allocations: 3.522 MB / 0.68 GB, free: 0.8945 MB / 0.6355 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.27/0.4175, allocations: 2 MB / 0.682 GB, free: 161.4 MB / 0.6355 GB Notification: Performance of pre-optimization done (n=57): time 3.126e-06/0.4175, allocations: 0 / 0.682 GB, free: 161.4 MB / 0.6355 GB Notification: Performance of matching and sorting (n=57): time 0.01093/0.4284, allocations: 5.199 MB / 0.687 GB, free: 160.9 MB / 0.6355 GB Notification: Performance of inlineWhenForInitialization (initialization): time 3.385e-05/0.4285, allocations: 64.61 kB / 0.6871 GB, free: 160.9 MB / 0.6355 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001707/0.4302, allocations: 2.007 MB / 0.6891 GB, free: 160.1 MB / 0.6355 GB Notification: Performance of collectPreVariables (initialization): time 8.037e-05/0.4302, allocations: 39.55 kB / 0.6891 GB, free: 160.1 MB / 0.6355 GB Notification: Performance of collectInitialEqns (initialization): time 0.0004861/0.4307, allocations: 1.085 MB / 0.6902 GB, free: 159.4 MB / 0.6355 GB Notification: Performance of collectInitialBindings (initialization): time 0.0001253/0.4309, allocations: 204.7 kB / 0.6904 GB, free: 159.3 MB / 0.6355 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002375/0.4311, allocations: 216 kB / 0.6906 GB, free: 159.2 MB / 0.6355 GB Notification: Performance of setup shared object (initialization): time 4.481e-05/0.4311, allocations: 343.1 kB / 0.6909 GB, free: 158.9 MB / 0.6355 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.00354/0.4347, allocations: 1.784 MB / 0.6926 GB, free: 158.5 MB / 0.6355 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.00377/0.4384, allocations: 2.402 MB / 0.695 GB, free: 157.3 MB / 0.6355 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.006698/0.4451, allocations: 3.501 MB / 0.6984 GB, free: 156.2 MB / 0.6355 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.536e-06/0.4452, allocations: 2.719 kB / 0.6984 GB, free: 156.2 MB / 0.6355 GB Notification: Performance of matching and sorting (n=119) (initialization): time 0.007339/0.4525, allocations: 3.944 MB / 0.7022 GB, free: 155.2 MB / 0.6355 GB Notification: Performance of prepare postOptimizeDAE: time 3.988e-05/0.4525, allocations: 44.14 kB / 0.7023 GB, free: 155.2 MB / 0.6355 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.537e-05/0.4525, allocations: 11.19 kB / 0.7023 GB, free: 155.2 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (initialization): time 3.88e-05/0.4526, allocations: 17 kB / 0.7023 GB, free: 155.2 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002128/0.4547, allocations: 0.8817 MB / 0.7032 GB, free: 155.1 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.632e-05/0.4547, allocations: 25.09 kB / 0.7032 GB, free: 155.1 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002278/0.457, allocations: 0.7769 MB / 0.704 GB, free: 155 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.86e-05/0.4571, allocations: 58.84 kB / 0.704 GB, free: 155 MB / 0.6355 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.004452/0.4615, allocations: 2.19 MB / 0.7062 GB, free: 154.3 MB / 0.6355 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003861/0.4654, allocations: 2.398 MB / 0.7085 GB, free: 153.2 MB / 0.6355 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.00693/0.4723, allocations: 3.505 MB / 0.7119 GB, free: 152 MB / 0.6355 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 8.075e-06/0.4723, allocations: 6.125 kB / 0.7119 GB, free: 152 MB / 0.6355 GB Notification: Performance of matching and sorting (n=119) (initialization_lambda0): time 0.008348/0.4807, allocations: 3.944 MB / 0.7158 GB, free: 151.1 MB / 0.6355 GB Notification: Performance of prepare postOptimizeDAE: time 3.119e-05/0.4807, allocations: 42.84 kB / 0.7158 GB, free: 151 MB / 0.6355 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.137e-05/0.4807, allocations: 10.16 kB / 0.7158 GB, free: 151 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (initialization): time 1.597e-05/0.4807, allocations: 18.08 kB / 0.7158 GB, free: 151 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002344/0.4831, allocations: 0.8814 MB / 0.7167 GB, free: 150.9 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.927e-05/0.4831, allocations: 22.75 kB / 0.7167 GB, free: 150.9 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002684/0.4858, allocations: 0.7641 MB / 0.7175 GB, free: 150.8 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.323e-05/0.4859, allocations: 57.41 kB / 0.7175 GB, free: 150.8 MB / 0.6355 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 52 * Number of states: 0 () * Number of discrete variables: 6 (TSet.count,$PRE.TSet.count,TSet.T_start,$PRE.TSet.T_start,$whenCondition1,conPI.I.trigger_internal) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (117): * Single equations (assignments): 115 * Array equations: 1 * Algorithm blocks: 1 * 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.0002895/0.4862, allocations: 251.2 kB / 0.7178 GB, free: 150.6 MB / 0.6355 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002209/0.4864, allocations: 212.6 kB / 0.718 GB, free: 150.4 MB / 0.6355 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008697/0.4951, allocations: 3.848 MB / 0.7217 GB, free: 149.6 MB / 0.6355 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.781e-05/0.4951, allocations: 15.31 kB / 0.7217 GB, free: 149.6 MB / 0.6355 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 9.428e-06/0.4951, allocations: 1.859 kB / 0.7217 GB, free: 149.6 MB / 0.6355 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.007521/0.5026, allocations: 3.585 MB / 0.7252 GB, free: 148.7 MB / 0.6355 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.009418/0.5121, allocations: 5.615 MB / 0.7307 GB, free: 146.1 MB / 0.6355 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.449e-06/0.5121, allocations: 4.062 kB / 0.7307 GB, free: 146.1 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002018/0.5141, allocations: 0.7754 MB / 0.7315 GB, free: 145.8 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.0001577/0.5142, allocations: 33.84 kB / 0.7315 GB, free: 145.8 MB / 0.6355 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.8e-05/0.5143, allocations: 27.69 kB / 0.7316 GB, free: 145.8 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0006196/0.5149, allocations: 1.342 MB / 0.7329 GB, free: 144.4 MB / 0.6355 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.262e-06/0.5149, allocations: 0 / 0.7329 GB, free: 144.4 MB / 0.6355 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01064/0.5256, allocations: 5.73 MB / 0.7385 GB, free: 139.7 MB / 0.6355 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0005727/0.5261, allocations: 363.7 kB / 0.7388 GB, free: 139.3 MB / 0.6355 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000138/0.5263, allocations: 19.98 kB / 0.7388 GB, free: 139.3 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002022/0.5283, allocations: 0.6865 MB / 0.7395 GB, free: 138.6 MB / 0.6355 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 8.985e-05/0.5284, allocations: 52.56 kB / 0.7395 GB, free: 138.6 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.292e-05/0.5284, allocations: 35.95 kB / 0.7396 GB, free: 138.6 MB / 0.6355 GB Notification: Performance of sorting global known variables: time 0.001206/0.5297, allocations: 1.353 MB / 0.7409 GB, free: 137.2 MB / 0.6355 GB Notification: Performance of sort global known variables: time 8e-08/0.5297, allocations: 0 / 0.7409 GB, free: 137.2 MB / 0.6355 GB Notification: Performance of remove unused functions: time 0.001743/0.5314, allocations: 0.9688 MB / 0.7418 GB, free: 136.3 MB / 0.6355 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 3 * Number of states: 5 (vol.dynBal.U,mov.motSpe.y,conPI.I.y,THeaOut.T,coo.outCon.T) * Number of discrete variables: 3 ($whenCondition1,TSet.T_start,TSet.count) * Number of discrete states: 1 (TSet.count) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (66): * Single equations (assignments): 57 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 6 * When equations: 2 * 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 {(1,2,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.00107/0.5325, allocations: 0.7012 MB / 0.7425 GB, free: 135.9 MB / 0.6355 GB Notification: Performance of simCode: created initialization part: time 0.01084/0.5433, allocations: 5.751 MB / 0.7481 GB, free: 130.1 MB / 0.6355 GB Notification: Performance of simCode: created event and clocks part: time 3.046e-06/0.5433, allocations: 0 / 0.7481 GB, free: 130.1 MB / 0.6355 GB Notification: Performance of simCode: created simulation system equations: time 0.003681/0.547, allocations: 1.932 MB / 0.75 GB, free: 128.2 MB / 0.6355 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002247/0.5492, allocations: 0.9702 MB / 0.751 GB, free: 127.3 MB / 0.6355 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006409/0.5556, allocations: 5.066 MB / 0.7559 GB, free: 122.2 MB / 0.6355 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004777/0.5561, allocations: 0.9541 MB / 0.7569 GB, free: 121.2 MB / 0.6355 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005329/0.5567, allocations: 199 kB / 0.757 GB, free: 121 MB / 0.6355 GB Notification: Performance of SimCode: time 1.102e-06/0.5567, allocations: 4 kB / 0.7571 GB, free: 121 MB / 0.6355 GB Notification: Performance of Templates: time 0.02945/0.5861, allocations: 44.1 MB / 0.8001 GB, free: 77.25 MB / 0.6355 GB " [Timeout remaining time 659] make -j1 -f IBPSA_latest_IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T.makefile [Timeout 660] (rm -f IBPSA_latest_IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T.sim & ./IBPSA_latest_IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.HeatExchangers.Examples.WaterCooler_T.pipe 2>&1) [Timeout 480] [Calling sys.exit(0), Time elapsed: 11.059918833896518] Failed to read output from testmodel.py, exit status != 0: 0.5921319387853146 0.5978018199999999 0.507999605 Calling exit ...