Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.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.001811/0.001811, allocations: 85.48 kB / 19.44 MB, free: 344 kB / 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.001966/0.001966, allocations: 173.2 kB / 22.76 MB, free: 1.664 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 1.633/1.633, allocations: 177.1 MB / 203.1 MB, free: 5.609 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-545:108:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-545:116:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.order:0:0-0:0:readonly] Warning: Found duplicate names in package.order file: A, B, C.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.mo:2:1-70:10:writable] Warning: C was referenced in the package.order file, but was not found in package.mo, C/package.mo or C.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo): time 1.342/1.342, allocations: 154.6 MB / 414.2 MB, free: 0.6328 MB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 178]
Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.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: translateModel(IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor")
translateModel(IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.804e-06/1.804e-06, allocations: 0 / 0.5196 GB, free: 2.504 MB / 490.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 3.264e-05/3.444e-05, allocations: 2.312 kB / 0.5196 GB, free: 2.5 MB / 490.7 MB
Notification: Performance of NFInst.instantiate(IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor): time 0.7439/0.744, allocations: 300.1 MB / 0.8127 GB, free: 19.17 MB / 0.6355 GB
Notification: Performance of NFInst.instExpressions: time 0.02069/0.7646, allocations: 15.75 MB / 0.828 GB, free: 9.301 MB / 0.6355 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004119/0.7688, allocations: 91.75 kB / 0.8281 GB, free: 9.301 MB / 0.6355 GB
Notification: Performance of NFTyping.typeComponents: time 0.004728/0.7735, allocations: 1.667 MB / 0.8298 GB, free: 8.68 MB / 0.6355 GB
Notification: Performance of NFTyping.typeBindings: time 0.01448/0.788, allocations: 6.33 MB / 0.8359 GB, free: 5.27 MB / 0.6355 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006785/0.7948, allocations: 2.952 MB / 0.8388 GB, free: 3.738 MB / 0.6355 GB
Notification: Performance of NFFlatten.flatten: time 0.007857/0.8026, allocations: 7.347 MB / 0.846 GB, free: 15.7 MB / 0.6511 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003384/0.806, allocations: 2.094 MB / 0.848 GB, free: 14.23 MB / 0.6511 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.007047/0.813, allocations: 3.899 MB / 0.8519 GB, free: 11.87 MB / 0.6511 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005399/0.8184, allocations: 2.488 MB / 0.8543 GB, free: 9.93 MB / 0.6511 GB
Notification: Performance of NFPackage.collectConstants: time 0.0008165/0.8193, allocations: 340 kB / 0.8546 GB, free: 9.598 MB / 0.6511 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01213/0.8314, allocations: 4.801 MB / 0.8593 GB, free: 4.781 MB / 0.6511 GB
Notification: Performance of NFScalarize.scalarize: time 0.002731/0.8341, allocations: 1.629 MB / 0.8609 GB, free: 3.148 MB / 0.6511 GB
Notification: Performance of NFVerifyModel.verify: time 0.003941/0.8381, allocations: 1.399 MB / 0.8623 GB, free: 1.746 MB / 0.6511 GB
Notification: Performance of NFConvertDAE.convert: time 0.01688/0.8549, allocations: 6.992 MB / 0.8691 GB, free: 10.73 MB / 0.6667 GB
Notification: Performance of FrontEnd - DAE generated: time 7.684e-06/0.8549, allocations: 3.984 kB / 0.8691 GB, free: 10.73 MB / 0.6667 GB
Notification: Performance of FrontEnd: time 1.553e-06/0.855, allocations: 0 / 0.8691 GB, free: 10.73 MB / 0.6667 GB
Notification: Performance of Transformations before backend: time 0.0001268/0.8551, allocations: 0 / 0.8691 GB, free: 10.73 MB / 0.6667 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 747
 * Number of variables: 747
Notification: Performance of Generate backend data structure: time 0.01156/0.8666, allocations: 4.395 MB / 0.8734 GB, free: 6.273 MB / 0.6667 GB
Notification: Performance of prepare preOptimizeDAE: time 4.321e-05/0.8667, allocations: 12.02 kB / 0.8734 GB, free: 6.262 MB / 0.6667 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006009/0.8727, allocations: 1.19 MB / 0.8746 GB, free: 5.07 MB / 0.6667 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004506/0.8772, allocations: 1.757 MB / 0.8763 GB, free: 3.262 MB / 0.6667 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002007/0.8774, allocations: 213.6 kB / 0.8765 GB, free: 3.051 MB / 0.6667 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005575/0.878, allocations: 291.9 kB / 0.8767 GB, free: 2.766 MB / 0.6667 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.006289/0.8842, allocations: 3.291 MB / 0.88 GB, free: 15.29 MB / 0.6823 GB
Notification: Performance of preOpt findStateOrder (simulation): time 6.458e-05/0.8843, allocations: 0 / 0.88 GB, free: 15.29 MB / 0.6823 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002963/0.8846, allocations: 120 kB / 0.8801 GB, free: 15.17 MB / 0.6823 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002196/0.8848, allocations: 169.6 kB / 0.8802 GB, free: 15 MB / 0.6823 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005681/0.8905, allocations: 2.532 MB / 0.8827 GB, free: 12.46 MB / 0.6823 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.01817/0.9087, allocations: 9.518 MB / 0.892 GB, free: 2.773 MB / 0.6823 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004302/0.913, allocations: 2.233 MB / 0.8942 GB, free: 0.5469 MB / 0.6823 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002237/0.9152, allocations: 1.107 MB / 0.8953 GB, free: 15.44 MB / 0.698 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001137/0.9164, allocations: 258 kB / 0.8955 GB, free: 15.18 MB / 0.698 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.252e-05/0.9164, allocations: 67.64 kB / 0.8956 GB, free: 15.11 MB / 0.698 GB
Notification: Performance of pre-optimization done (n=285): time 4.829e-06/0.9164, allocations: 0 / 0.8956 GB, free: 15.11 MB / 0.698 GB
Notification: Performance of matching and sorting (n=285): time 0.006565/0.923, allocations: 2.76 MB / 0.8983 GB, free: 12.3 MB / 0.698 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.12e-05/0.9231, allocations: 81.66 kB / 0.8984 GB, free: 12.21 MB / 0.698 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002277/0.9254, allocations: 1.434 MB / 0.8998 GB, free: 10.74 MB / 0.698 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002068/0.9256, allocations: 77.7 kB / 0.8998 GB, free: 10.66 MB / 0.698 GB
Notification: Performance of collectInitialEqns (initialization): time 0.00116/0.9267, allocations: 1.191 MB / 0.901 GB, free: 9.457 MB / 0.698 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001257/0.928, allocations: 1.077 MB / 0.902 GB, free: 8.371 MB / 0.698 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0008946/0.9289, allocations: 0.5117 MB / 0.9025 GB, free: 7.852 MB / 0.698 GB
Notification: Performance of setup shared object (initialization): time 0.0002468/0.9291, allocations: 484.7 kB / 0.903 GB, free: 7.375 MB / 0.698 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001574/0.9307, allocations: 0.6805 MB / 0.9037 GB, free: 6.691 MB / 0.698 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002297/0.933, allocations: 1.118 MB / 0.9048 GB, free: 5.496 MB / 0.698 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003942/0.9369, allocations: 2.041 MB / 0.9068 GB, free: 3.367 MB / 0.698 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.47e-05/0.9369, allocations: 4 kB / 0.9068 GB, free: 3.363 MB / 0.698 GB
Notification: Performance of matching and sorting (n=333) (initialization): time 0.005148/0.9421, allocations: 2.301 MB / 0.909 GB, free: 1.023 MB / 0.698 GB
Notification: Performance of prepare postOptimizeDAE: time 3.34e-05/0.9421, allocations: 8.406 kB / 0.909 GB, free: 1.016 MB / 0.698 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.813e-05/0.9421, allocations: 11.98 kB / 0.909 GB, free: 1.004 MB / 0.698 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.421e-05/0.9422, allocations: 12 kB / 0.909 GB, free: 0.9922 MB / 0.698 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002563/0.9447, allocations: 0.5383 MB / 0.9096 GB, free: 464 kB / 0.698 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 4.078e-05/0.9448, allocations: 19.94 kB / 0.9096 GB, free: 444 kB / 0.698 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002197/0.947, allocations: 190.5 kB / 0.9098 GB, free: 252 kB / 0.698 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002238/0.9472, allocations: 91.98 kB / 0.9098 GB, free: 160 kB / 0.698 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 (333):
 * Single equations (assignments): 333
 * 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.0013/0.9485, allocations: 463.9 kB / 0.9103 GB, free: 15.69 MB / 0.7136 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008219/0.9493, allocations: 314.6 kB / 0.9106 GB, free: 15.38 MB / 0.7136 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005538/0.9549, allocations: 2.298 MB / 0.9128 GB, free: 13.04 MB / 0.7136 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.246e-05/0.9549, allocations: 28 kB / 0.9129 GB, free: 13.01 MB / 0.7136 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.534e-06/0.9549, allocations: 0 / 0.9129 GB, free: 13.01 MB / 0.7136 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003593/0.9585, allocations: 1.767 MB / 0.9146 GB, free: 11.19 MB / 0.7136 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01156/0.9701, allocations: 5.094 MB / 0.9196 GB, free: 5.938 MB / 0.7136 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.436e-06/0.9701, allocations: 320 / 0.9196 GB, free: 5.938 MB / 0.7136 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002736/0.9728, allocations: 0.5461 MB / 0.9201 GB, free: 5.391 MB / 0.7136 GB
Notification: Performance of postOpt tearingSystem (simulation): time 2.653e-05/0.9728, allocations: 12 kB / 0.9201 GB, free: 5.379 MB / 0.7136 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000218/0.973, allocations: 71.97 kB / 0.9202 GB, free: 5.309 MB / 0.7136 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.863e-05/0.9731, allocations: 12 kB / 0.9202 GB, free: 5.297 MB / 0.7136 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.276e-06/0.9731, allocations: 0 / 0.9202 GB, free: 5.297 MB / 0.7136 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.006714/0.9798, allocations: 3.425 MB / 0.9235 GB, free: 1.727 MB / 0.7136 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001839/0.9816, allocations: 0.5172 MB / 0.924 GB, free: 1.207 MB / 0.7136 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004361/0.9821, allocations: 55.98 kB / 0.9241 GB, free: 1.152 MB / 0.7136 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001598/0.9837, allocations: 63.98 kB / 0.9242 GB, free: 1.09 MB / 0.7136 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004073/0.9841, allocations: 181.8 kB / 0.9243 GB, free: 0.9102 MB / 0.7136 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002025/0.9843, allocations: 83.98 kB / 0.9244 GB, free: 0.8281 MB / 0.7136 GB
Notification: Performance of sorting global known variables: time 0.00174/0.986, allocations: 0.8664 MB / 0.9253 GB, free: 15.96 MB / 0.7292 GB
Notification: Performance of sort global known variables: time 9e-08/0.986, allocations: 4 kB / 0.9253 GB, free: 15.96 MB / 0.7292 GB
Notification: Performance of remove unused functions: time 0.002751/0.9888, allocations: 1.053 MB / 0.9263 GB, free: 14.9 MB / 0.7292 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 11
 * Number of states: 18 (volEas.dynBal.medium.Xi[1],volEas.dynBal.U,volEas.dynBal.m,volEas.dynBal.mC[1],oriOutBot.mExc,oriOutTop.mExc,oriWesTop.mExc,dooOpeClo.mExcAB,dooOpeClo.mExcBA,volWes.dynBal.medium.Xi[1],volWes.dynBal.U,volWes.dynBal.m,volWes.dynBal.mC[1],oriEasTop.mExc,volTop.dynBal.medium.Xi[1],volTop.dynBal.U,volTop.dynBal.m,volTop.dynBal.mC[1])
 * 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 (285):
 * Single equations (assignments): 285
 * 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.0008071/0.9896, allocations: 390.8 kB / 0.9267 GB, free: 14.52 MB / 0.7292 GB
Notification: Performance of simCode: created initialization part: time 0.004321/0.9939, allocations: 2.006 MB / 0.9286 GB, free: 12.48 MB / 0.7292 GB
Notification: Performance of simCode: created event and clocks part: time 8.085e-06/0.9939, allocations: 8.062 kB / 0.9286 GB, free: 12.47 MB / 0.7292 GB
Notification: Performance of simCode: created simulation system equations: time 0.002185/0.9961, allocations: 1.13 MB / 0.9297 GB, free: 11.33 MB / 0.7292 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006131/1.002, allocations: 1.197 MB / 0.9309 GB, free: 10.19 MB / 0.7292 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01156/1.014, allocations: 5.447 MB / 0.9362 GB, free: 4.629 MB / 0.7292 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001918/1.016, allocations: 1.363 MB / 0.9375 GB, free: 3.227 MB / 0.7292 GB
Notification: Performance of simCode: alias equations: time 0.003338/1.019, allocations: 0.7446 MB / 0.9383 GB, free: 2.477 MB / 0.7292 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0007953/1.02, allocations: 208.5 kB / 0.9385 GB, free: 2.273 MB / 0.7292 GB
Notification: Performance of SimCode: time 1.553e-06/1.02, allocations: 0 / 0.9385 GB, free: 2.273 MB / 0.7292 GB
Notification: Performance of Templates: time 0.343/1.363, allocations: 47.55 MB / 0.9849 GB, free: 292 MB / 0.7448 GB
"
[Timeout remaining time 659]
make -j1 -f IDEAS_IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.makefile [Timeout 660]
(rm -f IDEAS_IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe ; mkfifo IDEAS_IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe ; head -c 1048576 < IDEAS_IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe >> ../files/IDEAS_IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.sim & ./IDEAS_IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor -abortSlowSimulation -alarm=240  -lv LOG_STATS > IDEAS_IDEAS.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 9.199601205065846]