Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.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.0007536/0.0007536, allocations: 79.69 kB / 19.25 MB, free: 0.6211 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.0007668/0.0007668, allocations: 162 kB / 22.56 MB, free: 1.965 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.8546/0.8546, allocations: 177.1 MB / 202.9 MB, free: 5.707 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/package.mo", uses=false) [Timeout 180] "[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/Building/BuildingEnvelope/HeatTransfer/package.mo:2:1-40:17:writable] Warning: Soil_case_nappe was referenced in the package.order file, but was not found in package.mo, Soil_case_nappe/package.mo or Soil_case_nappe.mo. Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/package.mo): time 0.5265/0.5265, allocations: 110.2 MB / 369.6 MB, free: 12.62 MB / 346.7 MB [/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/BoundaryConditions/Scenarios/CombiTimeTable.mo:44:3-56:20:writable] Warning: External function annotation should occur on the external-clause, not on the function. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/BoundaryConditions/Scenarios/CombiTimeTable.mo:58:3-65:19:writable] Warning: External function annotation should occur on the external-clause, not on the function. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/BoundaryConditions/Scenarios/CombiTimeTable.mo:67:3-72:20:writable] Warning: External function annotation should occur on the external-clause, not on the function. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.0/BoundaryConditions/Scenarios/CombiTimeTable.mo:74:3-79:20:writable] Warning: External function annotation should occur on the external-clause, not on the function. " [Timeout remaining time 179] Using package BuildSysPro with version 3.6.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.7.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(BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors,fileNamePrefix="BuildSysPro_BuildSysPro_IBPSA_Airflow_Multizone_Examples_ClosedDoors",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors,fileNamePrefix="BuildSysPro_BuildSysPro_IBPSA_Airflow_Multizone_Examples_ClosedDoors",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.292e-06/1.292e-06, allocations: 0 / 0.5475 GB, free: 8.699 MB / 474.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 1.38e-05/1.509e-05, allocations: 9.031 kB / 0.5475 GB, free: 8.691 MB / 474.7 MB Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors): time 0.3962/0.3962, allocations: 193.3 MB / 0.7362 GB, free: 2.898 MB / 0.573 GB Notification: Performance of NFInst.instExpressions: time 0.01782/0.414, allocations: 18.93 MB / 0.7547 GB, free: 4.047 MB / 0.5886 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.002315/0.4163, allocations: 75.12 kB / 0.7548 GB, free: 4.012 MB / 0.5886 GB Notification: Performance of NFTyping.typeComponents: time 0.002212/0.4185, allocations: 1.204 MB / 0.756 GB, free: 3.043 MB / 0.5886 GB Notification: Performance of NFTyping.typeBindings: time 0.007655/0.4262, allocations: 4.348 MB / 0.7602 GB, free: 15.7 MB / 0.6042 GB Notification: Performance of NFTyping.typeClassSections: time 0.002968/0.4292, allocations: 1.606 MB / 0.7618 GB, free: 14.42 MB / 0.6042 GB Notification: Performance of NFFlatten.flatten: time 0.006172/0.4353, allocations: 7.159 MB / 0.7688 GB, free: 10.19 MB / 0.6042 GB Notification: Performance of NFFlatten.resolveConnections: time 0.002378/0.4377, allocations: 1.807 MB / 0.7705 GB, free: 8.883 MB / 0.6042 GB Notification: Performance of NFEvalConstants.evaluate: time 0.004663/0.4424, allocations: 3.333 MB / 0.7738 GB, free: 6.438 MB / 0.6042 GB Notification: Performance of NFSimplifyModel.simplify: time 0.003477/0.4459, allocations: 2.333 MB / 0.7761 GB, free: 4.109 MB / 0.6042 GB Notification: Performance of NFPackage.collectConstants: time 0.000449/0.4463, allocations: 344 kB / 0.7764 GB, free: 3.773 MB / 0.6042 GB Notification: Performance of NFFlatten.collectFunctions: time 0.004544/0.4509, allocations: 2.945 MB / 0.7793 GB, free: 0.8203 MB / 0.6042 GB Notification: Performance of NFScalarize.scalarize: time 0.001078/0.4519, allocations: 1.551 MB / 0.7808 GB, free: 15.27 MB / 0.6198 GB Notification: Performance of NFVerifyModel.verify: time 0.001507/0.4534, allocations: 1.372 MB / 0.7821 GB, free: 13.89 MB / 0.6198 GB Notification: Performance of NFConvertDAE.convert: time 0.007054/0.4605, allocations: 6.167 MB / 0.7882 GB, free: 7.707 MB / 0.6198 GB Notification: Performance of FrontEnd - DAE generated: time 6.422e-06/0.4605, allocations: 0 / 0.7882 GB, free: 7.707 MB / 0.6198 GB Notification: Performance of FrontEnd: time 1.904e-06/0.4605, allocations: 0 / 0.7882 GB, free: 7.707 MB / 0.6198 GB Notification: Performance of Transformations before backend: time 7.639e-05/0.4606, allocations: 4 kB / 0.7882 GB, free: 7.703 MB / 0.6198 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 697 * Number of variables: 697 Notification: Performance of Generate backend data structure: time 0.005748/0.4663, allocations: 3.938 MB / 0.792 GB, free: 3.715 MB / 0.6198 GB Notification: Performance of prepare preOptimizeDAE: time 4.451e-05/0.4664, allocations: 12.03 kB / 0.792 GB, free: 3.703 MB / 0.6198 GB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0003444/0.4667, allocations: 391.1 kB / 0.7924 GB, free: 3.316 MB / 0.6198 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002732/0.4694, allocations: 0.8605 MB / 0.7932 GB, free: 2.453 MB / 0.6198 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.002511/0.472, allocations: 1.953 MB / 0.7951 GB, free: 456 kB / 0.6198 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 9.361e-05/0.4721, allocations: 197.4 kB / 0.7953 GB, free: 256 kB / 0.6198 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002723/0.4723, allocations: 279.9 kB / 0.7956 GB, free: 15.97 MB / 0.6355 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.003765/0.4761, allocations: 3.655 MB / 0.7992 GB, free: 12.19 MB / 0.6355 GB Notification: Performance of preOpt findStateOrder (simulation): time 4.498e-05/0.4761, allocations: 8 kB / 0.7992 GB, free: 12.18 MB / 0.6355 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001789/0.4763, allocations: 108 kB / 0.7993 GB, free: 12.07 MB / 0.6355 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.895e-05/0.4764, allocations: 121.4 kB / 0.7994 GB, free: 11.95 MB / 0.6355 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004814/0.4812, allocations: 4.561 MB / 0.8038 GB, free: 7.379 MB / 0.6355 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.009787/0.491, allocations: 7.629 MB / 0.8113 GB, free: 15.69 MB / 0.6511 GB Notification: Performance of preOpt comSubExp (simulation): time 0.003919/0.4949, allocations: 4.512 MB / 0.8157 GB, free: 11.13 MB / 0.6511 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.002246/0.4972, allocations: 2.289 MB / 0.8179 GB, free: 8.836 MB / 0.6511 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0004849/0.4976, allocations: 179 kB / 0.8181 GB, free: 8.66 MB / 0.6511 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.29e-05/0.4977, allocations: 85.62 kB / 0.8182 GB, free: 8.566 MB / 0.6511 GB Notification: Performance of pre-optimization done (n=399): time 5.51e-06/0.4977, allocations: 0 / 0.8182 GB, free: 8.566 MB / 0.6511 GB Notification: Performance of matching and sorting (n=399): time 0.00651/0.5042, allocations: 5.81 MB / 0.8239 GB, free: 2.727 MB / 0.6511 GB Notification: Performance of inlineWhenForInitialization (initialization): time 5.049e-05/0.5043, allocations: 94.55 kB / 0.8239 GB, free: 2.621 MB / 0.6511 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001264/0.5055, allocations: 1.236 MB / 0.8252 GB, free: 1.367 MB / 0.6511 GB Notification: Performance of collectPreVariables (initialization): time 0.0001748/0.5057, allocations: 93.7 kB / 0.8252 GB, free: 1.27 MB / 0.6511 GB Notification: Performance of collectInitialEqns (initialization): time 0.0004149/0.5061, allocations: 0.819 MB / 0.826 GB, free: 452 kB / 0.6511 GB Notification: Performance of collectInitialBindings (initialization): time 0.000823/0.5069, allocations: 1.386 MB / 0.8274 GB, free: 15.04 MB / 0.6667 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005503/0.5075, allocations: 0.543 MB / 0.8279 GB, free: 14.49 MB / 0.6667 GB Notification: Performance of setup shared object (initialization): time 0.0001126/0.5076, allocations: 309.1 kB / 0.8282 GB, free: 14.18 MB / 0.6667 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.001672/0.5093, allocations: 1.606 MB / 0.8298 GB, free: 12.58 MB / 0.6667 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.001836/0.5111, allocations: 1.909 MB / 0.8317 GB, free: 10.62 MB / 0.6667 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.003781/0.5149, allocations: 3.831 MB / 0.8354 GB, free: 6.734 MB / 0.6667 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.075e-05/0.5149, allocations: 0 / 0.8354 GB, free: 6.734 MB / 0.6667 GB Notification: Performance of matching and sorting (n=430) (initialization): time 0.004468/0.5194, allocations: 4.204 MB / 0.8395 GB, free: 2.508 MB / 0.6667 GB Notification: Performance of prepare postOptimizeDAE: time 2.622e-05/0.5194, allocations: 8 kB / 0.8395 GB, free: 2.5 MB / 0.6667 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.673e-05/0.5194, allocations: 14.48 kB / 0.8395 GB, free: 2.492 MB / 0.6667 GB Notification: Performance of postOpt tearingSystem (initialization): time 2.463e-05/0.5194, allocations: 16 kB / 0.8395 GB, free: 2.477 MB / 0.6667 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001577/0.521, allocations: 0.6749 MB / 0.8402 GB, free: 1.801 MB / 0.6667 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.282e-05/0.521, allocations: 19.94 kB / 0.8402 GB, free: 1.781 MB / 0.6667 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0013/0.5223, allocations: 254 kB / 0.8405 GB, free: 1.531 MB / 0.6667 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001499/0.5225, allocations: 116 kB / 0.8406 GB, free: 1.418 MB / 0.6667 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 14 * 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 (430): * Single equations (assignments): 430 * 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.0009823/0.5235, allocations: 0.7235 MB / 0.8413 GB, free: 0.6836 MB / 0.6667 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003317/0.5238, allocations: 250.5 kB / 0.8415 GB, free: 448 kB / 0.6667 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004777/0.5286, allocations: 4.632 MB / 0.846 GB, free: 11.76 MB / 0.6823 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.064e-05/0.5286, allocations: 43.94 kB / 0.8461 GB, free: 11.71 MB / 0.6823 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.025e-05/0.5286, allocations: 0 / 0.8461 GB, free: 11.71 MB / 0.6823 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003815/0.5324, allocations: 3.954 MB / 0.8499 GB, free: 7.703 MB / 0.6823 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.008416/0.5408, allocations: 7.693 MB / 0.8575 GB, free: 15.92 MB / 0.698 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.782e-06/0.5408, allocations: 8 kB / 0.8575 GB, free: 15.91 MB / 0.698 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001593/0.5424, allocations: 0.6553 MB / 0.8581 GB, free: 15.26 MB / 0.698 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.0002914/0.5427, allocations: 138.4 kB / 0.8582 GB, free: 15.11 MB / 0.698 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001488/0.5429, allocations: 91.91 kB / 0.8583 GB, free: 15.02 MB / 0.698 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002436/0.5453, allocations: 4.704 MB / 0.8629 GB, free: 9.984 MB / 0.698 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.312e-06/0.5453, allocations: 4 kB / 0.8629 GB, free: 9.98 MB / 0.698 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.007115/0.5524, allocations: 6.833 MB / 0.8696 GB, free: 3.086 MB / 0.698 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001372/0.5538, allocations: 0.7083 MB / 0.8703 GB, free: 2.375 MB / 0.698 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002487/0.5541, allocations: 68 kB / 0.8703 GB, free: 2.309 MB / 0.698 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001024/0.5551, allocations: 85.8 kB / 0.8704 GB, free: 2.227 MB / 0.698 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003174/0.5554, allocations: 234.7 kB / 0.8707 GB, free: 1.996 MB / 0.698 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001514/0.5555, allocations: 116 kB / 0.8708 GB, free: 1.883 MB / 0.698 GB Notification: Performance of sorting global known variables: time 0.0008509/0.5564, allocations: 0.8606 MB / 0.8716 GB, free: 1.02 MB / 0.698 GB Notification: Performance of sort global known variables: time 1.7e-07/0.5564, allocations: 0 / 0.8716 GB, free: 1.02 MB / 0.698 GB Notification: Performance of remove unused functions: time 0.00165/0.558, allocations: 1.077 MB / 0.8727 GB, free: 15.94 MB / 0.7136 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 1 * Number of states: 15 (dooAB.mExcAB,dooAB.mExcBA,volA.dynBal.medium.Xi[1],volA.dynBal.U,volA.dynBal.m,volB.dynBal.medium.Xi[1],volB.dynBal.U,volB.dynBal.m,volC.dynBal.medium.Xi[1],volC.dynBal.U,volC.dynBal.m,dooAC.mExcAB,dooAC.mExcBA,dooBC.mExcAB,dooBC.mExcBA) * 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 (393): * Single equations (assignments): 390 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 3 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 3 systems {(1,2,100.0%), (1,2,100.0%), (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.007032/0.5651, allocations: 7.637 MB / 0.8801 GB, free: 8.242 MB / 0.7136 GB Notification: Performance of simCode: created initialization part: time 0.003998/0.5691, allocations: 3.82 MB / 0.8838 GB, free: 4.41 MB / 0.7136 GB Notification: Performance of simCode: created event and clocks part: time 3.737e-06/0.5691, allocations: 0 / 0.8838 GB, free: 4.41 MB / 0.7136 GB Notification: Performance of simCode: created simulation system equations: time 0.002225/0.5713, allocations: 2.365 MB / 0.8862 GB, free: 2.008 MB / 0.7136 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0028/0.5741, allocations: 1.068 MB / 0.8872 GB, free: 0.9531 MB / 0.7136 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02331/0.5974, allocations: 22.91 MB / 0.9096 GB, free: 9.754 MB / 0.7448 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009496/0.5984, allocations: 1.362 MB / 0.9109 GB, free: 8.352 MB / 0.7448 GB Notification: Performance of simCode: alias equations: time 0.002258/0.6006, allocations: 1.223 MB / 0.9121 GB, free: 7.121 MB / 0.7448 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001271/0.6019, allocations: 1.094 MB / 0.9132 GB, free: 6.027 MB / 0.7448 GB Notification: Performance of SimCode: time 1.473e-06/0.6019, allocations: 0 / 0.9132 GB, free: 6.027 MB / 0.7448 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.2886/0.8905, allocations: 71.76 MB / 0.9832 GB, free: 276.3 MB / 0.7605 GB Notification: Performance of buildModelFMU: Generate platform static: time 8.738/9.628, allocations: 15.23 kB / 0.9833 GB, free: 276.3 MB / 0.7605 GB " [Timeout remaining time 650] (rm -f BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.sim & python3 -m fmpy simulate --output-file BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors_res.csv --start-time 0 --stop-time 7200 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 2.88 BuildSysPro_BuildSysPro_IBPSA_Airflow_Multizone_Examples_ClosedDoors.fmu > BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.ClosedDoors.pipe 2>&1) [Timeout 52.5] [Calling sys.exit(0), Time elapsed: 12.06709842570126] Failed to read output from testmodel.py, exit status != 0: 9.746179336681962 9.752933089 9.290886955 Calling exit ...