Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.FMI.Validation.HeaterFan.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.001045/0.001045, allocations: 77.09 kB / 20.65 MB, free: 3.844 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.001225/0.001225, allocations: 169 kB / 24.05 MB, free: 456 kB / 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.946/0.946, allocations: 177.1 MB / 204.5 MB, free: 5.5 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.6674/0.6674, allocations: 115 MB / 376 MB, free: 7.48 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.FMI.Validation.HeaterFan,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.FMI.Validation.HeaterFan") translateModel(IBPSA.Fluid.FMI.Validation.HeaterFan,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.FMI.Validation.HeaterFan") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.673e-06/1.673e-06, allocations: 0 / 481.9 MB, free: 11.77 MB / 410.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 2.435e-05/2.602e-05, allocations: 5.609 kB / 482 MB, free: 11.76 MB / 410.7 MB Notification: Performance of NFInst.instantiate(IBPSA.Fluid.FMI.Validation.HeaterFan): time 0.1477/0.1477, allocations: 157.3 MB / 0.6243 GB, free: 10.05 MB / 0.5417 GB Notification: Performance of NFInst.instExpressions: time 0.01604/0.1638, allocations: 10.4 MB / 0.6345 GB, free: 15.62 MB / 0.5573 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.002898/0.1667, allocations: 111.1 kB / 0.6346 GB, free: 15.51 MB / 0.5573 GB Notification: Performance of NFTyping.typeComponents: time 0.004708/0.1714, allocations: 1.691 MB / 0.6362 GB, free: 13.81 MB / 0.5573 GB Notification: Performance of NFTyping.typeBindings: time 0.005238/0.1766, allocations: 2.292 MB / 0.6385 GB, free: 11.51 MB / 0.5573 GB Notification: Performance of NFTyping.typeClassSections: time 0.00463/0.1812, allocations: 2.285 MB / 0.6407 GB, free: 9.223 MB / 0.5573 GB Notification: Performance of NFFlatten.flatten: time 0.009997/0.1912, allocations: 8.352 MB / 0.6489 GB, free: 0.8438 MB / 0.5573 GB Notification: Performance of NFFlatten.resolveConnections: time 0.2583/0.4495, allocations: 1.651 MB / 0.6505 GB, free: 21.54 MB / 0.5573 GB Notification: Performance of NFEvalConstants.evaluate: time 0.009465/0.459, allocations: 3.114 MB / 0.6535 GB, free: 21.54 MB / 0.5573 GB Notification: Performance of NFSimplifyModel.simplify: time 0.002868/0.4618, allocations: 1.316 MB / 0.6548 GB, free: 21.54 MB / 0.5573 GB Notification: Performance of NFPackage.collectConstants: time 0.000689/0.4625, allocations: 234.5 kB / 0.655 GB, free: 21.54 MB / 0.5573 GB Notification: Performance of NFFlatten.collectFunctions: time 0.002255/0.4648, allocations: 1.122 MB / 0.6561 GB, free: 21.54 MB / 0.5573 GB Notification: Performance of NFScalarize.scalarize: time 0.0007731/0.4656, allocations: 0.8469 MB / 0.6569 GB, free: 21.54 MB / 0.5573 GB Notification: Performance of NFVerifyModel.verify: time 0.001767/0.4673, allocations: 0.861 MB / 0.6578 GB, free: 21.54 MB / 0.5573 GB Notification: Performance of NFConvertDAE.convert: time 0.004221/0.4715, allocations: 2.504 MB / 0.6602 GB, free: 21.38 MB / 0.5573 GB Notification: Performance of FrontEnd - DAE generated: time 6.362e-06/0.4715, allocations: 0.5625 kB / 0.6602 GB, free: 21.38 MB / 0.5573 GB Notification: Performance of FrontEnd: time 1.152e-06/0.4715, allocations: 0 / 0.6602 GB, free: 21.38 MB / 0.5573 GB Notification: Performance of Transformations before backend: time 8e-05/0.4716, allocations: 2.219 kB / 0.6602 GB, free: 21.38 MB / 0.5573 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 332 * Number of variables: 332 Notification: Performance of Generate backend data structure: time 0.004003/0.4756, allocations: 2.92 MB / 0.6631 GB, free: 19.86 MB / 0.5573 GB Notification: Performance of prepare preOptimizeDAE: time 4.61e-05/0.4757, allocations: 11.12 kB / 0.6631 GB, free: 19.86 MB / 0.5573 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003059/0.4787, allocations: 0.92 MB / 0.664 GB, free: 19.66 MB / 0.5573 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.007894/0.4866, allocations: 5.463 MB / 0.6693 GB, free: 19.06 MB / 0.5573 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 9.194e-05/0.4867, allocations: 103.7 kB / 0.6694 GB, free: 19.06 MB / 0.5573 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001944/0.4869, allocations: 138.3 kB / 0.6696 GB, free: 19.06 MB / 0.5573 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.001937/0.4889, allocations: 1.093 MB / 0.6706 GB, free: 18.72 MB / 0.5573 GB Notification: Performance of preOpt findStateOrder (simulation): time 2.537e-05/0.4889, allocations: 5.328 kB / 0.6706 GB, free: 18.72 MB / 0.5573 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.906e-05/0.489, allocations: 54.22 kB / 0.6707 GB, free: 18.72 MB / 0.5573 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.043e-05/0.489, allocations: 53.62 kB / 0.6707 GB, free: 18.71 MB / 0.5573 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0009671/0.49, allocations: 438.2 kB / 0.6711 GB, free: 18.67 MB / 0.5573 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.007187/0.4972, allocations: 5.387 MB / 0.6764 GB, free: 16.25 MB / 0.5573 GB Notification: Performance of preOpt comSubExp (simulation): time 0.0007665/0.498, allocations: 358.2 kB / 0.6767 GB, free: 16.21 MB / 0.5573 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0001707/0.4981, allocations: 115.7 kB / 0.6769 GB, free: 16.2 MB / 0.5573 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0001087/0.4982, allocations: 25.31 kB / 0.6769 GB, free: 16.2 MB / 0.5573 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.207e-05/0.4983, allocations: 37.45 kB / 0.6769 GB, free: 16.16 MB / 0.5573 GB Notification: Performance of pre-optimization done (n=35): time 2.905e-06/0.4983, allocations: 0 / 0.6769 GB, free: 16.16 MB / 0.5573 GB Notification: Performance of matching and sorting (n=35): time 0.001216/0.4995, allocations: 0.4969 MB / 0.6774 GB, free: 16.01 MB / 0.5573 GB Notification: Performance of inlineWhenForInitialization (initialization): time 2.5e-05/0.4995, allocations: 55.66 kB / 0.6775 GB, free: 15.95 MB / 0.5573 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00191/0.5014, allocations: 1.604 MB / 0.679 GB, free: 15.64 MB / 0.5573 GB Notification: Performance of collectPreVariables (initialization): time 7.479e-05/0.5015, allocations: 39.67 kB / 0.6791 GB, free: 15.61 MB / 0.5573 GB Notification: Performance of collectInitialEqns (initialization): time 0.0007722/0.5023, allocations: 1.178 MB / 0.6802 GB, free: 15.19 MB / 0.5573 GB Notification: Performance of collectInitialBindings (initialization): time 9.508e-05/0.5024, allocations: 133.2 kB / 0.6803 GB, free: 15.1 MB / 0.5573 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.00019/0.5025, allocations: 165.9 kB / 0.6805 GB, free: 15.07 MB / 0.5573 GB Notification: Performance of setup shared object (initialization): time 6.946e-05/0.5026, allocations: 0.5063 MB / 0.681 GB, free: 14.59 MB / 0.5573 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.000188/0.5028, allocations: 87.16 kB / 0.6811 GB, free: 14.57 MB / 0.5573 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0003234/0.5031, allocations: 0.6224 MB / 0.6817 GB, free: 13.88 MB / 0.5573 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.0005428/0.5037, allocations: 0.5806 MB / 0.6822 GB, free: 13.41 MB / 0.5573 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.001e-05/0.5037, allocations: 2.969 kB / 0.6823 GB, free: 13.41 MB / 0.5573 GB Notification: Performance of matching and sorting (n=81) (initialization): time 0.001176/0.5049, allocations: 0.6721 MB / 0.6829 GB, free: 13.26 MB / 0.5573 GB Notification: Performance of prepare postOptimizeDAE: time 2.312e-05/0.5049, allocations: 8.562 kB / 0.6829 GB, free: 13.26 MB / 0.5573 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.842e-05/0.5049, allocations: 9.844 kB / 0.6829 GB, free: 13.26 MB / 0.5573 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.000123/0.505, allocations: 39 kB / 0.683 GB, free: 13.24 MB / 0.5573 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005288/0.5055, allocations: 186.6 kB / 0.6831 GB, free: 13.24 MB / 0.5573 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0008139/0.5064, allocations: 1.98 MB / 0.6851 GB, free: 11.32 MB / 0.5573 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00099/0.5074, allocations: 130.1 kB / 0.6852 GB, free: 11.28 MB / 0.5573 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.814e-05/0.5074, allocations: 33.83 kB / 0.6852 GB, free: 11.27 MB / 0.5573 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0006483/0.5081, allocations: 212.9 kB / 0.6854 GB, free: 11.14 MB / 0.5573 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.000306/0.5084, allocations: 0.6261 MB / 0.686 GB, free: 10.46 MB / 0.5573 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0005461/0.5089, allocations: 0.5667 MB / 0.6866 GB, free: 9.984 MB / 0.5573 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.086e-05/0.5089, allocations: 2.75 kB / 0.6866 GB, free: 9.984 MB / 0.5573 GB Notification: Performance of matching and sorting (n=81) (initialization_lambda0): time 0.001153/0.5101, allocations: 0.6552 MB / 0.6872 GB, free: 9.836 MB / 0.5573 GB Notification: Performance of prepare postOptimizeDAE: time 1.619e-05/0.5101, allocations: 8.719 kB / 0.6872 GB, free: 9.836 MB / 0.5573 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.424e-05/0.5101, allocations: 8.969 kB / 0.6873 GB, free: 9.836 MB / 0.5573 GB Notification: Performance of postOpt tearingSystem (initialization): time 8.276e-05/0.5102, allocations: 43.91 kB / 0.6873 GB, free: 9.824 MB / 0.5573 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004798/0.5107, allocations: 179.8 kB / 0.6875 GB, free: 9.82 MB / 0.5573 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001039/0.5117, allocations: 1.99 MB / 0.6894 GB, free: 23.89 MB / 0.573 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008196/0.5125, allocations: 106.7 kB / 0.6895 GB, free: 23.87 MB / 0.573 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.361e-05/0.5126, allocations: 28.75 kB / 0.6895 GB, free: 23.87 MB / 0.573 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 40 * 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 (78): * Single equations (assignments): 76 * Array equations: 1 * 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): 1 system {(1,2,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.0002453/0.5128, allocations: 320.2 kB / 0.6898 GB, free: 23.6 MB / 0.573 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001807/0.513, allocations: 194.8 kB / 0.69 GB, free: 23.5 MB / 0.573 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0008005/0.5138, allocations: 437.8 kB / 0.6905 GB, free: 23.4 MB / 0.573 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.068e-06/0.5138, allocations: 6.844 kB / 0.6905 GB, free: 23.4 MB / 0.573 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 2.605e-06/0.5138, allocations: 0.8125 kB / 0.6905 GB, free: 23.4 MB / 0.573 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.607e-06/0.5138, allocations: 0 / 0.6905 GB, free: 23.4 MB / 0.573 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001878/0.5157, allocations: 1.308 MB / 0.6917 GB, free: 23 MB / 0.573 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.238e-06/0.5157, allocations: 3.625 kB / 0.6917 GB, free: 23 MB / 0.573 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0002643/0.516, allocations: 83.25 kB / 0.6918 GB, free: 23 MB / 0.573 GB Notification: Performance of postOpt tearingSystem (simulation): time 6.558e-05/0.516, allocations: 32.33 kB / 0.6919 GB, free: 22.99 MB / 0.573 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 1.973e-05/0.516, allocations: 10.77 kB / 0.6919 GB, free: 22.99 MB / 0.573 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0007934/0.5168, allocations: 1.972 MB / 0.6938 GB, free: 21.07 MB / 0.573 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.194e-06/0.5168, allocations: 4.625 kB / 0.6938 GB, free: 21.07 MB / 0.573 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0005952/0.5174, allocations: 0.631 MB / 0.6944 GB, free: 20.66 MB / 0.573 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0002955/0.5177, allocations: 229 kB / 0.6946 GB, free: 20.55 MB / 0.573 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 8.648e-05/0.5178, allocations: 7.75 kB / 0.6946 GB, free: 20.55 MB / 0.573 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003688/0.5182, allocations: 36.78 kB / 0.6947 GB, free: 20.55 MB / 0.573 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 3.473e-05/0.5182, allocations: 29.52 kB / 0.6947 GB, free: 20.55 MB / 0.573 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 2.754e-05/0.5183, allocations: 19.69 kB / 0.6947 GB, free: 20.55 MB / 0.573 GB Notification: Performance of sorting global known variables: time 0.0007956/0.519, allocations: 0.8495 MB / 0.6955 GB, free: 20.36 MB / 0.573 GB Notification: Performance of sort global known variables: time 3.51e-07/0.519, allocations: 0 / 0.6955 GB, free: 20.36 MB / 0.573 GB Notification: Performance of remove unused functions: time 0.0017/0.5207, allocations: 0.8526 MB / 0.6964 GB, free: 20.19 MB / 0.573 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 8 * 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 simulation (33): * Single equations (assignments): 32 * 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): 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.0003378/0.5211, allocations: 177 kB / 0.6965 GB, free: 20.09 MB / 0.573 GB Notification: Performance of simCode: created initialization part: time 0.001073/0.5222, allocations: 0.876 MB / 0.6974 GB, free: 19.71 MB / 0.573 GB Notification: Performance of simCode: created event and clocks part: time 3.206e-06/0.5222, allocations: 0 / 0.6974 GB, free: 19.71 MB / 0.573 GB Notification: Performance of simCode: created simulation system equations: time 0.0002137/0.5224, allocations: 287.2 kB / 0.6977 GB, free: 19.52 MB / 0.573 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002079/0.5245, allocations: 0.9987 MB / 0.6987 GB, free: 19.4 MB / 0.573 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003606/0.5281, allocations: 2.857 MB / 0.7014 GB, free: 17.94 MB / 0.573 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0002988/0.5284, allocations: 421.3 kB / 0.7018 GB, free: 17.84 MB / 0.573 GB Notification: Performance of simCode: alias equations: time 0.001246/0.5296, allocations: 337.7 kB / 0.7022 GB, free: 17.79 MB / 0.573 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001172/0.5308, allocations: 0.7268 MB / 0.7029 GB, free: 17.72 MB / 0.573 GB Notification: Performance of SimCode: time 1.472e-06/0.5308, allocations: 0 / 0.7029 GB, free: 17.72 MB / 0.573 GB Notification: Performance of Templates: time 0.04229/0.5731, allocations: 35.22 MB / 0.7373 GB, free: 5.449 MB / 0.573 GB " [Timeout remaining time 659] make -j1 -f IBPSA_latest_IBPSA.Fluid.FMI.Validation.HeaterFan.makefile [Timeout 660] (rm -f IBPSA_latest_IBPSA.Fluid.FMI.Validation.HeaterFan.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.FMI.Validation.HeaterFan.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.FMI.Validation.HeaterFan.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.FMI.Validation.HeaterFan.sim & ./IBPSA_latest_IBPSA.Fluid.FMI.Validation.HeaterFan -abortSlowSimulation -alarm=240 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.FMI.Validation.HeaterFan.pipe 2>&1) [Timeout 240] [Calling sys.exit(0), Time elapsed: 4.634397116024047]