Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData.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.001025/0.001025, allocations: 76.5 kB / 19.93 MB, free: 4.633 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.000892/0.000892, allocations: 173.9 kB / 23.21 MB, free: 1.355 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.92/0.92, allocations: 177.1 MB / 203.5 MB, free: 5.738 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 1.868/1.868, allocations: 389 MB / 0.6338 GB, free: 12.7 MB / 0.6042 GB " [Timeout remaining time 177] Using package Buildings with version 13.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 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(Buildings.Airflow.Multizone.Examples.PressurizationData,tolerance=1e-06,outputFormat="mat",numberOfIntervals=4321,variableFilter="time|pow_1dat1.V_flow|pow_1dat.V_flow|pow_1dat1.dp|pow_1dat.dp",fileNamePrefix="Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData") translateModel(Buildings.Airflow.Multizone.Examples.PressurizationData,tolerance=1e-06,outputFormat="mat",numberOfIntervals=4321,variableFilter="time|pow_1dat1.V_flow|pow_1dat.V_flow|pow_1dat1.dp|pow_1dat.dp",fileNamePrefix="Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData") [Timeout 300] "Notification: Performance of FrontEnd - loaded program: time 1.864e-06/1.864e-06, allocations: 0 / 1.001 GB, free: 13.02 MB / 0.9636 GB Notification: Performance of FrontEnd - Absyn->SCode: time 2.513e-05/2.699e-05, allocations: 2.281 kB / 1.001 GB, free: 13.02 MB / 0.9636 GB Notification: Performance of NFInst.instantiate(Buildings.Airflow.Multizone.Examples.PressurizationData): time 0.6949/0.6949, allocations: 165.3 MB / 1.162 GB, free: 11.98 MB / 1.026 GB Notification: Performance of NFInst.instExpressions: time 0.01987/0.7148, allocations: 15.1 MB / 1.177 GB, free: 1.797 MB / 1.026 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.002354/0.7171, allocations: 56 kB / 1.177 GB, free: 1.77 MB / 1.026 GB Notification: Performance of NFTyping.typeComponents: time 0.001919/0.7191, allocations: 0.7098 MB / 1.178 GB, free: 1.348 MB / 1.026 GB Notification: Performance of NFTyping.typeBindings: time 0.007067/0.7261, allocations: 2.465 MB / 1.18 GB, free: 15.95 MB / 1.042 GB Notification: Performance of NFTyping.typeClassSections: time 0.005335/0.7315, allocations: 1.874 MB / 1.182 GB, free: 14.95 MB / 1.042 GB Notification: Performance of NFFlatten.flatten: time 0.006601/0.7381, allocations: 3.776 MB / 1.186 GB, free: 13.07 MB / 1.042 GB Notification: Performance of NFFlatten.resolveConnections: time 0.002622/0.7407, allocations: 1.46 MB / 1.187 GB, free: 12.3 MB / 1.042 GB Notification: Performance of NFEvalConstants.evaluate: time 0.00479/0.7455, allocations: 2.336 MB / 1.189 GB, free: 11.29 MB / 1.042 GB Notification: Performance of NFSimplifyModel.simplify: time 0.001454/0.7469, allocations: 1.085 MB / 1.191 GB, free: 10.9 MB / 1.042 GB Notification: Performance of NFPackage.collectConstants: time 0.0003223/0.7473, allocations: 224.8 kB / 1.191 GB, free: 10.9 MB / 1.042 GB Notification: Performance of NFFlatten.collectFunctions: time 0.004507/0.7518, allocations: 1.778 MB / 1.192 GB, free: 10.34 MB / 1.042 GB Notification: Performance of NFScalarize.scalarize: time 0.000635/0.7524, allocations: 0.7042 MB / 1.193 GB, free: 9.926 MB / 1.042 GB Notification: Performance of NFVerifyModel.verify: time 0.001308/0.7537, allocations: 0.808 MB / 1.194 GB, free: 9.555 MB / 1.042 GB Notification: Performance of NFConvertDAE.convert: time 0.005054/0.7588, allocations: 3.824 MB / 1.198 GB, free: 8.102 MB / 1.042 GB Notification: Performance of FrontEnd - DAE generated: time 6.953e-06/0.7588, allocations: 0 / 1.198 GB, free: 8.102 MB / 1.042 GB Notification: Performance of FrontEnd: time 3.146e-06/0.7588, allocations: 0 / 1.198 GB, free: 8.102 MB / 1.042 GB Notification: Performance of Transformations before backend: time 2.455e-05/0.7588, allocations: 1.188 kB / 1.198 GB, free: 8.102 MB / 1.042 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 426 * Number of variables: 426 Notification: Performance of Generate backend data structure: time 0.003574/0.7624, allocations: 2.575 MB / 1.2 GB, free: 6.316 MB / 1.042 GB Notification: Performance of prepare preOptimizeDAE: time 6.497e-05/0.7624, allocations: 15.27 kB / 1.2 GB, free: 6.312 MB / 1.042 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002717/0.7652, allocations: 0.6733 MB / 1.201 GB, free: 6 MB / 1.042 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.001913/0.7671, allocations: 1.268 MB / 1.202 GB, free: 5.047 MB / 1.042 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001245/0.7672, allocations: 128.5 kB / 1.202 GB, free: 4.988 MB / 1.042 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002543/0.7674, allocations: 172.8 kB / 1.202 GB, free: 4.93 MB / 1.042 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.002971/0.7704, allocations: 2.555 MB / 1.205 GB, free: 2.965 MB / 1.042 GB Notification: Performance of preOpt findStateOrder (simulation): time 3.425e-05/0.7705, allocations: 1.281 kB / 1.205 GB, free: 2.965 MB / 1.042 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001391/0.7706, allocations: 74.12 kB / 1.205 GB, free: 2.91 MB / 1.042 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.099e-05/0.7707, allocations: 66.27 kB / 1.205 GB, free: 2.902 MB / 1.042 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002637/0.7733, allocations: 1.925 MB / 1.207 GB, free: 1.949 MB / 1.042 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.006277/0.7796, allocations: 4.82 MB / 1.212 GB, free: 14.4 MB / 1.057 GB Notification: Performance of preOpt comSubExp (simulation): time 0.002052/0.7816, allocations: 1.489 MB / 1.213 GB, free: 13.65 MB / 1.057 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0008247/0.7825, allocations: 0.6682 MB / 1.214 GB, free: 13.31 MB / 1.057 GB Notification: Performance of preOpt evalFunc (simulation): time 0.000492/0.783, allocations: 150.8 kB / 1.214 GB, free: 13.25 MB / 1.057 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001224/0.7842, allocations: 1.398 MB / 1.215 GB, free: 12.11 MB / 1.057 GB Notification: Performance of pre-optimization done (n=150): time 2.705e-06/0.7842, allocations: 3.938 kB / 1.215 GB, free: 12.11 MB / 1.057 GB Notification: Performance of matching and sorting (n=150): time 0.003831/0.788, allocations: 2.296 MB / 1.217 GB, free: 10.97 MB / 1.057 GB Notification: Performance of inlineWhenForInitialization (initialization): time 8.339e-05/0.7881, allocations: 203.8 kB / 1.218 GB, free: 10.75 MB / 1.057 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0008847/0.789, allocations: 1.012 MB / 1.219 GB, free: 10.18 MB / 1.057 GB Notification: Performance of collectPreVariables (initialization): time 9.392e-05/0.7891, allocations: 68.31 kB / 1.219 GB, free: 10.11 MB / 1.057 GB Notification: Performance of collectInitialEqns (initialization): time 0.0003894/0.7895, allocations: 0.7414 MB / 1.219 GB, free: 9.461 MB / 1.057 GB Notification: Performance of collectInitialBindings (initialization): time 0.0003002/0.7898, allocations: 0.5087 MB / 1.22 GB, free: 9.008 MB / 1.057 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002866/0.79, allocations: 272.9 kB / 1.22 GB, free: 8.848 MB / 1.057 GB Notification: Performance of setup shared object (initialization): time 6.99e-05/0.7901, allocations: 303.3 kB / 1.22 GB, free: 8.551 MB / 1.057 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007419/0.7909, allocations: 0.5765 MB / 1.221 GB, free: 8.258 MB / 1.057 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009168/0.7918, allocations: 0.9258 MB / 1.222 GB, free: 7.59 MB / 1.057 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.0016/0.7934, allocations: 1.317 MB / 1.223 GB, free: 6.871 MB / 1.057 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.967e-06/0.7934, allocations: 0 / 1.223 GB, free: 6.871 MB / 1.057 GB Notification: Performance of matching and sorting (n=181) (initialization): time 0.002326/0.7957, allocations: 1.745 MB / 1.225 GB, free: 5.828 MB / 1.057 GB Notification: Performance of prepare postOptimizeDAE: time 2.002e-05/0.7957, allocations: 8.156 kB / 1.225 GB, free: 5.82 MB / 1.057 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.728e-06/0.7957, allocations: 4.875 kB / 1.225 GB, free: 5.816 MB / 1.057 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.0004141/0.7962, allocations: 146.5 kB / 1.225 GB, free: 5.664 MB / 1.057 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007675/0.7969, allocations: 327.5 kB / 1.225 GB, free: 5.344 MB / 1.057 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002771/0.7997, allocations: 4.16 MB / 1.229 GB, free: 0.8828 MB / 1.057 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008065/0.8005, allocations: 151.2 kB / 1.23 GB, free: 0.7344 MB / 1.057 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.21e-05/0.8006, allocations: 64.08 kB / 1.23 GB, free: 0.6719 MB / 1.057 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 9 * Number of states: 0 () * Number of discrete variables: 27 (weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,weaDat.decAng.k,$PRE.weaDat.decAng.k,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.eqnTim.k,$PRE.weaDat.eqnTim.k,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.conTim.k,$PRE.weaDat.conTim.k,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.k,$PRE.weaDat.conTimMin.k,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.locTim.k,$PRE.weaDat.locTim.k,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (175): * Single equations (assignments): 172 * 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): 2 systems {(1,2,100.0%), (1,2,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,2)} Notification: Performance of prepare postOptimizeDAE: time 0.0005411/0.8011, allocations: 428.1 kB / 1.23 GB, free: 248 kB / 1.057 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000189/0.8013, allocations: 155.1 kB / 1.23 GB, free: 92 kB / 1.057 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002464/0.8038, allocations: 1.915 MB / 1.232 GB, free: 14.15 MB / 1.073 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.626e-06/0.8038, allocations: 16 kB / 1.232 GB, free: 14.13 MB / 1.073 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 5.34e-06/0.8038, allocations: 3.938 kB / 1.232 GB, free: 14.13 MB / 1.073 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001924/0.8057, allocations: 1.595 MB / 1.234 GB, free: 12.49 MB / 1.073 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.00425/0.81, allocations: 3.39 MB / 1.237 GB, free: 9.055 MB / 1.073 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.038e-06/0.81, allocations: 0 / 1.237 GB, free: 9.055 MB / 1.073 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.000727/0.8107, allocations: 315.5 kB / 1.237 GB, free: 8.746 MB / 1.073 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.0002885/0.811, allocations: 134.4 kB / 1.237 GB, free: 8.605 MB / 1.073 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.955e-05/0.8111, allocations: 43.95 kB / 1.237 GB, free: 8.562 MB / 1.073 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002623/0.8137, allocations: 4.148 MB / 1.242 GB, free: 4.102 MB / 1.073 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.623e-06/0.8137, allocations: 4 kB / 1.242 GB, free: 4.098 MB / 1.073 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.00321/0.8169, allocations: 2.637 MB / 1.244 GB, free: 1.434 MB / 1.073 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0005591/0.8174, allocations: 362.5 kB / 1.244 GB, free: 1.078 MB / 1.073 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001351/0.8176, allocations: 40.19 kB / 1.244 GB, free: 1.039 MB / 1.073 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006351/0.8182, allocations: 99.89 kB / 1.245 GB, free: 0.9414 MB / 1.073 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001703/0.8184, allocations: 113.1 kB / 1.245 GB, free: 0.832 MB / 1.073 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.413e-05/0.8185, allocations: 67.94 kB / 1.245 GB, free: 0.7656 MB / 1.073 GB Notification: Performance of sorting global known variables: time 0.0006565/0.8191, allocations: 0.685 MB / 1.245 GB, free: 76 kB / 1.073 GB Notification: Performance of sort global known variables: time 6e-08/0.8191, allocations: 4 kB / 1.245 GB, free: 72 kB / 1.073 GB Notification: Performance of remove unused functions: time 0.001525/0.8207, allocations: 0.678 MB / 1.246 GB, free: 15.39 MB / 1.089 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 1 * Number of states: 5 (room.dynBal.medium.Xi[1],room.dynBal.U,room.dynBal.m,pow_1dat.mExc,pow_1dat1.mExc) * Number of discrete variables: 15 ($whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,weaDat.locTim.tNext,weaDat.locTim.k,weaDat.conTimMin.tNext,weaDat.conTimMin.k,weaDat.conTim.tNext,weaDat.conTim.k,weaDat.eqnTim.tNext,weaDat.eqnTim.k,weaDat.decAng.tNext,weaDat.decAng.k) * Number of discrete states: 10 (weaDat.conTim.tNext,weaDat.eqnTim.tNext,weaDat.locTim.tNext,weaDat.decAng.tNext,weaDat.conTimMin.tNext,weaDat.decAng.k,weaDat.eqnTim.k,weaDat.conTim.k,weaDat.conTimMin.k,weaDat.locTim.k) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (156): * Single equations (assignments): 143 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 10 * 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): 2 systems {(1,2,100.0%), (1,2,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,3)} Notification: Performance of Backend phase and start with SimCode phase: time 0.001369/0.822, allocations: 0.7957 MB / 1.247 GB, free: 14.8 MB / 1.089 GB Notification: Performance of simCode: created initialization part: time 0.002424/0.8244, allocations: 1.745 MB / 1.249 GB, free: 13.01 MB / 1.089 GB Notification: Performance of simCode: created event and clocks part: time 2.996e-06/0.8244, allocations: 0.5 kB / 1.249 GB, free: 13.01 MB / 1.089 GB Notification: Performance of simCode: created simulation system equations: time 0.001061/0.8255, allocations: 1.077 MB / 1.25 GB, free: 11.89 MB / 1.089 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001539/0.827, allocations: 0.6613 MB / 1.25 GB, free: 11.26 MB / 1.089 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004053/0.8311, allocations: 3.385 MB / 1.254 GB, free: 7.84 MB / 1.089 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005852/0.8317, allocations: 0.8146 MB / 1.254 GB, free: 6.984 MB / 1.089 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004283/0.8321, allocations: 200.3 kB / 1.255 GB, free: 6.785 MB / 1.089 GB Notification: Performance of SimCode: time 1.293e-06/0.8321, allocations: 0 / 1.255 GB, free: 6.785 MB / 1.089 GB Notification: Performance of Templates: time 0.03978/0.8719, allocations: 51.81 MB / 1.305 GB, free: 3.008 MB / 1.135 GB " [Timeout remaining time 299] make -j1 -f Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData.makefile [Timeout 300] (rm -f Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData.pipe ; mkfifo Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData.pipe ; head -c 1048576 < Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData.pipe >> ../files/Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData.sim & ./Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData -abortSlowSimulation -alarm=800 -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData.pipe 2>&1) [Timeout 800] diffSimulationResults("Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_Airflow_Multizone_Examples_PressurizationData.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_Buildings.Airflow.Multizone.Examples.PressurizationData.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300] "" [Timeout remaining time 300] "" Variables in the reference:time,pow_1dat1.V_flow,pow_1dat.V_flow,pow_1dat1.dp,pow_1dat.dp Variables in the result:pow_1dat.V_flow,pow_1dat.dp,pow_1dat1.V_flow,pow_1dat1.dp,time [Calling sys.exit(0), Time elapsed: 11.933602456003428]