Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OneWayFlow.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.0009245/0.0009245, 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.0009767/0.0009767, 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.9153/0.9153, allocations: 177.1 MB / 202.9 MB, free: 5.73 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.547/0.547, allocations: 110.2 MB / 369.6 MB, free: 12.63 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.Validation.OneWayFlow,fileNamePrefix="BuildSysPro_BuildSysPro_IBPSA_Airflow_Multizone_Validation_OneWayFlow",fmuType="me",version="2.0",platforms={"static"}) "" <> buildModelFMU(BuildSysPro.IBPSA.Airflow.Multizone.Validation.OneWayFlow,fileNamePrefix="BuildSysPro_BuildSysPro_IBPSA_Airflow_Multizone_Validation_OneWayFlow",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.653e-06/1.653e-06, allocations: 0 / 0.5475 GB, free: 9.773 MB / 0.5105 GB Notification: Performance of FrontEnd - Absyn->SCode: time 2.037e-05/2.202e-05, allocations: 5.234 kB / 0.5475 GB, free: 9.77 MB / 0.5105 GB Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Airflow.Multizone.Validation.OneWayFlow): time 0.4199/0.4199, allocations: 193.2 MB / 0.7362 GB, free: 39.66 MB / 0.6355 GB Notification: Performance of NFInst.instExpressions: time 0.02156/0.4414, allocations: 24.34 MB / 0.7599 GB, free: 36.07 MB / 0.6355 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.001791/0.4432, allocations: 41.62 kB / 0.76 GB, free: 36.07 MB / 0.6355 GB Notification: Performance of NFTyping.typeComponents: time 0.002324/0.4455, allocations: 1.138 MB / 0.7611 GB, free: 35.76 MB / 0.6355 GB Notification: Performance of NFTyping.typeBindings: time 0.006791/0.4523, allocations: 3.348 MB / 0.7644 GB, free: 34.29 MB / 0.6355 GB Notification: Performance of NFTyping.typeClassSections: time 0.003342/0.4557, allocations: 1.81 MB / 0.7661 GB, free: 33.47 MB / 0.6355 GB Notification: Performance of NFFlatten.flatten: time 0.003722/0.4594, allocations: 3.938 MB / 0.77 GB, free: 31.71 MB / 0.6355 GB Notification: Performance of NFFlatten.resolveConnections: time 0.001448/0.4609, allocations: 1.122 MB / 0.7711 GB, free: 31.21 MB / 0.6355 GB Notification: Performance of NFEvalConstants.evaluate: time 0.003901/0.4648, allocations: 2.882 MB / 0.7739 GB, free: 30.09 MB / 0.6355 GB Notification: Performance of NFSimplifyModel.simplify: time 0.001964/0.4667, allocations: 1.405 MB / 0.7752 GB, free: 29.72 MB / 0.6355 GB Notification: Performance of NFPackage.collectConstants: time 0.0003105/0.467, allocations: 124.9 kB / 0.7754 GB, free: 29.72 MB / 0.6355 GB Notification: Performance of NFFlatten.collectFunctions: time 0.002707/0.4697, allocations: 1.631 MB / 0.777 GB, free: 29.17 MB / 0.6355 GB Notification: Performance of NFScalarize.scalarize: time 0.0005055/0.4702, allocations: 0.9133 MB / 0.7779 GB, free: 28.77 MB / 0.6355 GB Notification: Performance of NFVerifyModel.verify: time 0.0007106/0.4709, allocations: 0.7643 MB / 0.7786 GB, free: 28.48 MB / 0.6355 GB Notification: Performance of NFConvertDAE.convert: time 0.002891/0.4738, allocations: 3.104 MB / 0.7816 GB, free: 27.45 MB / 0.6355 GB Notification: Performance of FrontEnd - DAE generated: time 3.838e-06/0.4738, allocations: 0 / 0.7816 GB, free: 27.45 MB / 0.6355 GB Notification: Performance of FrontEnd: time 1.693e-06/0.4738, allocations: 2.688 kB / 0.7816 GB, free: 27.45 MB / 0.6355 GB Notification: Performance of Transformations before backend: time 9.233e-05/0.4739, allocations: 4 kB / 0.7816 GB, free: 27.45 MB / 0.6355 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 343 * Number of variables: 343 Notification: Performance of Generate backend data structure: time 0.003925/0.4779, allocations: 2.866 MB / 0.7844 GB, free: 25.64 MB / 0.6355 GB Notification: Performance of prepare preOptimizeDAE: time 4.732e-05/0.4779, allocations: 8.719 kB / 0.7844 GB, free: 25.64 MB / 0.6355 GB Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0001574/0.4781, allocations: 199.3 kB / 0.7846 GB, free: 25.52 MB / 0.6355 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001173/0.4792, allocations: 463.6 kB / 0.7851 GB, free: 25.33 MB / 0.6355 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.002384/0.4816, allocations: 2.742 MB / 0.7878 GB, free: 24.16 MB / 0.6355 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.339e-05/0.4817, allocations: 93 kB / 0.7878 GB, free: 24.11 MB / 0.6355 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001077/0.4818, allocations: 136.2 kB / 0.788 GB, free: 24.06 MB / 0.6355 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.001375/0.4832, allocations: 1.597 MB / 0.7895 GB, free: 23.25 MB / 0.6355 GB Notification: Performance of preOpt findStateOrder (simulation): time 3.103e-05/0.4832, allocations: 12.73 kB / 0.7895 GB, free: 23.25 MB / 0.6355 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 5.906e-05/0.4832, allocations: 57.09 kB / 0.7896 GB, free: 23.21 MB / 0.6355 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.007e-05/0.4833, allocations: 91.45 kB / 0.7897 GB, free: 23.2 MB / 0.6355 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001047/0.4844, allocations: 1.051 MB / 0.7907 GB, free: 22.91 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.00399/0.4883, allocations: 6.909 MB / 0.7975 GB, free: 18.3 MB / 0.6355 GB Notification: Performance of preOpt comSubExp (simulation): time 0.001874/0.4902, allocations: 0.7057 MB / 0.7981 GB, free: 18.03 MB / 0.6355 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0003987/0.4906, allocations: 298.7 kB / 0.7984 GB, free: 17.92 MB / 0.6355 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0003113/0.4909, allocations: 84.55 kB / 0.7985 GB, free: 17.91 MB / 0.6355 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.82e-05/0.491, allocations: 42.28 kB / 0.7986 GB, free: 17.87 MB / 0.6355 GB Notification: Performance of pre-optimization done (n=50): time 2.665e-06/0.491, allocations: 4 kB / 0.7986 GB, free: 17.87 MB / 0.6355 GB Notification: Performance of matching and sorting (n=50): time 0.001573/0.4925, allocations: 0.9838 MB / 0.7995 GB, free: 17.53 MB / 0.6355 GB Notification: Performance of inlineWhenForInitialization (initialization): time 4.431e-05/0.4926, allocations: 161.7 kB / 0.7997 GB, free: 17.34 MB / 0.6355 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004031/0.4966, allocations: 4.702 MB / 0.8043 GB, free: 15.94 MB / 0.6355 GB Notification: Performance of collectPreVariables (initialization): time 3e-05/0.4966, allocations: 37.02 kB / 0.8043 GB, free: 15.9 MB / 0.6355 GB Notification: Performance of collectInitialEqns (initialization): time 0.000449/0.4971, allocations: 1.076 MB / 0.8053 GB, free: 15.07 MB / 0.6355 GB Notification: Performance of collectInitialBindings (initialization): time 7.956e-05/0.4972, allocations: 188 kB / 0.8055 GB, free: 14.91 MB / 0.6355 GB Notification: Performance of simplifyInitialFunctions (initialization): time 8.921e-05/0.4973, allocations: 96.45 kB / 0.8056 GB, free: 14.86 MB / 0.6355 GB Notification: Performance of setup shared object (initialization): time 3.397e-05/0.4973, allocations: 326.3 kB / 0.8059 GB, free: 14.55 MB / 0.6355 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0002125/0.4975, allocations: 214.2 kB / 0.8061 GB, free: 14.5 MB / 0.6355 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0002901/0.4978, allocations: 374.5 kB / 0.8065 GB, free: 14.29 MB / 0.6355 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.000474/0.4983, allocations: 0.5629 MB / 0.807 GB, free: 14.06 MB / 0.6355 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.106e-06/0.4983, allocations: 0 / 0.807 GB, free: 14.06 MB / 0.6355 GB Notification: Performance of matching and sorting (n=58) (initialization): time 0.0007004/0.499, allocations: 0.8165 MB / 0.8078 GB, free: 13.75 MB / 0.6355 GB Notification: Performance of prepare postOptimizeDAE: time 1.803e-05/0.499, allocations: 6.031 kB / 0.8078 GB, free: 13.75 MB / 0.6355 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.055e-06/0.499, allocations: 2.625 kB / 0.8078 GB, free: 13.75 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (initialization): time 1.706e-05/0.499, allocations: 5.469 kB / 0.8079 GB, free: 13.75 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0002366/0.4993, allocations: 103.9 kB / 0.808 GB, free: 13.75 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 9.177e-06/0.4993, allocations: 9.391 kB / 0.808 GB, free: 13.75 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0004761/0.4997, allocations: 98.42 kB / 0.8081 GB, free: 13.68 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 3.022e-05/0.4998, allocations: 20.28 kB / 0.8081 GB, free: 13.68 MB / 0.6355 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 11 * 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 (58): * Single equations (assignments): 58 * 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.0004239/0.5002, allocations: 0.5432 MB / 0.8086 GB, free: 13.45 MB / 0.6355 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001957/0.5004, allocations: 248.1 kB / 0.8088 GB, free: 13.32 MB / 0.6355 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0007229/0.5011, allocations: 0.8045 MB / 0.8096 GB, free: 13.05 MB / 0.6355 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.653e-06/0.5011, allocations: 8.656 kB / 0.8096 GB, free: 13.05 MB / 0.6355 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 6.452e-06/0.5011, allocations: 2.969 kB / 0.8096 GB, free: 13.05 MB / 0.6355 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.514e-06/0.5011, allocations: 6.922 kB / 0.8096 GB, free: 13.04 MB / 0.6355 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001816/0.5029, allocations: 1.936 MB / 0.8115 GB, free: 12.06 MB / 0.6355 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.156e-06/0.503, allocations: 3.031 kB / 0.8115 GB, free: 12.06 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0002317/0.5032, allocations: 95.19 kB / 0.8116 GB, free: 12.05 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (simulation): time 5.56e-06/0.5032, allocations: 6.562 kB / 0.8116 GB, free: 12.05 MB / 0.6355 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.192e-05/0.5032, allocations: 16.17 kB / 0.8116 GB, free: 12.05 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 5.06e-06/0.5032, allocations: 9.438 kB / 0.8117 GB, free: 12.05 MB / 0.6355 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.934e-06/0.5032, allocations: 0 / 0.8117 GB, free: 12.05 MB / 0.6355 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0009507/0.5042, allocations: 1.282 MB / 0.8129 GB, free: 11.48 MB / 0.6355 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0003873/0.5046, allocations: 362.7 kB / 0.8133 GB, free: 11.29 MB / 0.6355 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001375/0.5047, allocations: 14.64 kB / 0.8133 GB, free: 11.28 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009175/0.5056, allocations: 22.17 kB / 0.8133 GB, free: 11.27 MB / 0.6355 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001306/0.5057, allocations: 40.61 kB / 0.8133 GB, free: 11.27 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 2.351e-05/0.5058, allocations: 16.55 kB / 0.8133 GB, free: 11.27 MB / 0.6355 GB Notification: Performance of sorting global known variables: time 0.003245/0.509, allocations: 3.519 MB / 0.8168 GB, free: 10.15 MB / 0.6355 GB Notification: Performance of sort global known variables: time 2.01e-07/0.509, allocations: 0 / 0.8168 GB, free: 10.15 MB / 0.6355 GB Notification: Performance of remove unused functions: time 0.001404/0.5104, allocations: 487.8 kB / 0.8172 GB, free: 10.06 MB / 0.6355 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 7 * Number of states: 8 (ela.mExc,ori.mExc,pow_1dat.mExc,pow_2dat.mExc,pow_m_flow.mExc,pow_V_flow.mExc,tabDat_m_flow.mExc,tabDat_V_flow.mExc) * 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 (53): * Single equations (assignments): 53 * 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.002492/0.5129, allocations: 2.292 MB / 0.8195 GB, free: 8.84 MB / 0.6355 GB Notification: Performance of simCode: created initialization part: time 0.0007346/0.5136, allocations: 0.5275 MB / 0.82 GB, free: 8.727 MB / 0.6355 GB Notification: Performance of simCode: created event and clocks part: time 3.396e-06/0.5136, allocations: 0 / 0.82 GB, free: 8.727 MB / 0.6355 GB Notification: Performance of simCode: created simulation system equations: time 0.0002939/0.5139, allocations: 283 kB / 0.8203 GB, free: 8.672 MB / 0.6355 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002381/0.5163, allocations: 0.8621 MB / 0.8211 GB, free: 8.367 MB / 0.6355 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01807/0.5344, allocations: 19.28 MB / 0.8399 GB, free: 7.551 MB / 0.6511 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007518/0.5351, allocations: 1.066 MB / 0.841 GB, free: 6.445 MB / 0.6511 GB Notification: Performance of simCode: alias equations: time 0.001231/0.5364, allocations: 466.2 kB / 0.8414 GB, free: 5.988 MB / 0.6511 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000696/0.5371, allocations: 0.9494 MB / 0.8423 GB, free: 5.039 MB / 0.6511 GB Notification: Performance of SimCode: time 1.432e-06/0.5371, allocations: 0 / 0.8423 GB, free: 5.039 MB / 0.6511 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.06372/0.6008, allocations: 50.6 MB / 0.8918 GB, free: 3.367 MB / 0.698 GB Notification: Performance of buildModelFMU: Generate platform static: time 8.437/9.038, allocations: 0 / 0.8918 GB, free: 3.367 MB / 0.698 GB " [Timeout remaining time 651] (rm -f BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OneWayFlow.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OneWayFlow.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OneWayFlow.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OneWayFlow.sim & python3 -m fmpy simulate --output-file BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OneWayFlow_res.csv --start-time 0 --stop-time 500 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 1 BuildSysPro_BuildSysPro_IBPSA_Airflow_Multizone_Validation_OneWayFlow.fmu > BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OneWayFlow.pipe 2>&1) [Timeout 52.5] [Calling sys.exit(0), Time elapsed: 11.65804538037628] Failed to read output from testmodel.py, exit status != 0: 9.161739860661328 9.169732198 8.69393374 Calling exit ...