Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildingSystems_BuildingSystems.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.002486/0.002486, allocations: 85.48 kB / 19.44 MB, free: 340 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.003164/0.003164, allocations: 177.2 kB / 22.77 MB, free: 1.656 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.604/1.604, allocations: 177.1 MB / 203.1 MB, free: 5.59 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo): time 0.003391/0.003391, allocations: 233.6 kB / 259.8 MB, free: 12.83 MB / 250.7 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo): time 1.2/1.2, allocations: 138.4 MB / 454.7 MB, free: 1.5 MB / 362.7 MB
"
[Timeout remaining time 179]
Using package BuildingSystems with version 2.0.0-beta (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo)
Using package NcDataReader2 with version 2.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 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(BuildingSystems.Airflow.Multizone.Validation.OneWayFlow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.OneWayFlow")
translateModel(BuildingSystems.Airflow.Multizone.Validation.OneWayFlow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.OneWayFlow") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 2.394e-06/2.394e-06, allocations: 0 / 0.5543 GB, free: 0.8711 MB / 474.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 4.947e-05/5.187e-05, allocations: 2.312 kB / 0.5543 GB, free: 0.8672 MB / 474.7 MB
Notification: Performance of NFInst.instantiate(BuildingSystems.Airflow.Multizone.Validation.OneWayFlow): time 0.5666/0.5667, allocations: 215.8 MB / 0.7651 GB, free: 4.922 MB / 0.573 GB
Notification: Performance of NFInst.instExpressions: time 0.04136/0.6081, allocations: 27.21 MB / 0.7917 GB, free: 9.641 MB / 0.6042 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001718/0.6098, allocations: 39.69 kB / 0.7917 GB, free: 9.602 MB / 0.6042 GB
Notification: Performance of NFTyping.typeComponents: time 0.003121/0.6129, allocations: 1.138 MB / 0.7928 GB, free: 8.457 MB / 0.6042 GB
Notification: Performance of NFTyping.typeBindings: time 0.009193/0.6221, allocations: 3.347 MB / 0.7961 GB, free: 5.094 MB / 0.6042 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005038/0.6271, allocations: 1.814 MB / 0.7979 GB, free: 3.273 MB / 0.6042 GB
Notification: Performance of NFFlatten.flatten: time 0.006406/0.6335, allocations: 3.933 MB / 0.8017 GB, free: 15.32 MB / 0.6198 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002531/0.6361, allocations: 1.172 MB / 0.8028 GB, free: 14.11 MB / 0.6198 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006324/0.6424, allocations: 2.873 MB / 0.8056 GB, free: 11.23 MB / 0.6198 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002672/0.6451, allocations: 1.413 MB / 0.807 GB, free: 9.82 MB / 0.6198 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003212/0.6454, allocations: 124 kB / 0.8071 GB, free: 9.699 MB / 0.6198 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.004845/0.6502, allocations: 1.624 MB / 0.8087 GB, free: 8.07 MB / 0.6198 GB
Notification: Performance of NFScalarize.scalarize: time 0.001314/0.6515, allocations: 0.9152 MB / 0.8096 GB, free: 7.152 MB / 0.6198 GB
Notification: Performance of NFVerifyModel.verify: time 0.001288/0.6528, allocations: 0.744 MB / 0.8104 GB, free: 6.406 MB / 0.6198 GB
Notification: Performance of NFConvertDAE.convert: time 0.004896/0.6577, allocations: 2.936 MB / 0.8132 GB, free: 3.461 MB / 0.6198 GB
Notification: Performance of FrontEnd - DAE generated: time 4.378e-06/0.6577, allocations: 0 / 0.8132 GB, free: 3.461 MB / 0.6198 GB
Notification: Performance of FrontEnd: time 1.373e-06/0.6577, allocations: 0 / 0.8132 GB, free: 3.461 MB / 0.6198 GB
Notification: Performance of Transformations before backend: time 3.242e-05/0.6578, allocations: 0 / 0.8132 GB, free: 3.461 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: 343
 * Number of variables: 343
Notification: Performance of Generate backend data structure: time 0.005219/0.663, allocations: 3.043 MB / 0.8162 GB, free: 372 kB / 0.6198 GB
Notification: Performance of prepare preOptimizeDAE: time 4.003e-05/0.663, allocations: 8.75 kB / 0.8162 GB, free: 364 kB / 0.6198 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002211/0.6652, allocations: 0.5525 MB / 0.8167 GB, free: 15.8 MB / 0.6355 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002558/0.6678, allocations: 1.248 MB / 0.818 GB, free: 14.5 MB / 0.6355 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001043/0.6679, allocations: 94.53 kB / 0.818 GB, free: 14.41 MB / 0.6355 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002005/0.6681, allocations: 131.9 kB / 0.8182 GB, free: 14.28 MB / 0.6355 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002512/0.6706, allocations: 1.454 MB / 0.8196 GB, free: 12.64 MB / 0.6355 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.62e-05/0.6706, allocations: 11.92 kB / 0.8196 GB, free: 12.62 MB / 0.6355 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000122/0.6708, allocations: 59.98 kB / 0.8197 GB, free: 12.57 MB / 0.6355 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001265/0.6709, allocations: 85.97 kB / 0.8197 GB, free: 12.48 MB / 0.6355 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001495/0.6724, allocations: 0.6865 MB / 0.8204 GB, free: 11.79 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.01022/0.6826, allocations: 8.205 MB / 0.8284 GB, free: 3.34 MB / 0.6355 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0008792/0.6835, allocations: 465.9 kB / 0.8289 GB, free: 2.824 MB / 0.6355 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003716/0.6839, allocations: 194.3 kB / 0.8291 GB, free: 2.613 MB / 0.6355 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003623/0.6842, allocations: 96.03 kB / 0.8291 GB, free: 2.52 MB / 0.6355 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.773e-05/0.6843, allocations: 45.33 kB / 0.8292 GB, free: 2.465 MB / 0.6355 GB
Notification: Performance of pre-optimization done (n=50): time 2.465e-06/0.6843, allocations: 0 / 0.8292 GB, free: 2.465 MB / 0.6355 GB
Notification: Performance of matching and sorting (n=50): time 0.001445/0.6857, allocations: 0.6258 MB / 0.8298 GB, free: 1.832 MB / 0.6355 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001163/0.6858, allocations: 162.2 kB / 0.83 GB, free: 1.637 MB / 0.6355 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003262/0.6891, allocations: 2.133 MB / 0.832 GB, free: 15.46 MB / 0.6511 GB
Notification: Performance of collectPreVariables (initialization): time 5.214e-05/0.6891, allocations: 37.7 kB / 0.8321 GB, free: 15.41 MB / 0.6511 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001279/0.6904, allocations: 1.222 MB / 0.8333 GB, free: 14.18 MB / 0.6511 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002081/0.6906, allocations: 190.4 kB / 0.8335 GB, free: 13.99 MB / 0.6511 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001799/0.6908, allocations: 107.8 kB / 0.8336 GB, free: 13.88 MB / 0.6511 GB
Notification: Performance of setup shared object (initialization): time 0.0002773/0.6911, allocations: 0.5043 MB / 0.834 GB, free: 13.37 MB / 0.6511 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0002696/0.6914, allocations: 106.2 kB / 0.8341 GB, free: 13.26 MB / 0.6511 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004486/0.6918, allocations: 296.8 kB / 0.8344 GB, free: 12.92 MB / 0.6511 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0006794/0.6925, allocations: 373.8 kB / 0.8348 GB, free: 12.51 MB / 0.6511 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.85e-06/0.6925, allocations: 4 kB / 0.8348 GB, free: 12.5 MB / 0.6511 GB
Notification: Performance of matching and sorting (n=58) (initialization): time 0.001119/0.6936, allocations: 0.5664 MB / 0.8353 GB, free: 11.93 MB / 0.6511 GB
Notification: Performance of prepare postOptimizeDAE: time 1.927e-05/0.6936, allocations: 4.625 kB / 0.8354 GB, free: 11.93 MB / 0.6511 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.598e-06/0.6936, allocations: 4 kB / 0.8354 GB, free: 11.92 MB / 0.6511 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.984e-05/0.6937, allocations: 4 kB / 0.8354 GB, free: 11.92 MB / 0.6511 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004301/0.6941, allocations: 103.8 kB / 0.8355 GB, free: 11.82 MB / 0.6511 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.562e-05/0.6941, allocations: 8 kB / 0.8355 GB, free: 11.81 MB / 0.6511 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005639/0.6947, allocations: 39.7 kB / 0.8355 GB, free: 11.77 MB / 0.6511 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.03e-05/0.6947, allocations: 20 kB / 0.8355 GB, free: 11.75 MB / 0.6511 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.0004957/0.6952, allocations: 292.6 kB / 0.8358 GB, free: 11.45 MB / 0.6511 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003653/0.6956, allocations: 207.8 kB / 0.836 GB, free: 11.25 MB / 0.6511 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001108/0.6967, allocations: 0.5187 MB / 0.8365 GB, free: 10.73 MB / 0.6511 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.889e-06/0.6967, allocations: 8 kB / 0.8365 GB, free: 10.72 MB / 0.6511 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.213e-06/0.6967, allocations: 3.938 kB / 0.8365 GB, free: 10.71 MB / 0.6511 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.13e-05/0.6967, allocations: 7.984 kB / 0.8365 GB, free: 10.71 MB / 0.6511 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003888/0.7006, allocations: 2.173 MB / 0.8386 GB, free: 8.461 MB / 0.6511 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.861e-06/0.7006, allocations: 7.875 kB / 0.8387 GB, free: 8.453 MB / 0.6511 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004401/0.7011, allocations: 95.84 kB / 0.8387 GB, free: 8.359 MB / 0.6511 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.018e-05/0.7011, allocations: 8.078 kB / 0.8388 GB, free: 8.352 MB / 0.6511 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.774e-05/0.7011, allocations: 15.06 kB / 0.8388 GB, free: 8.34 MB / 0.6511 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 6.051e-06/0.7011, allocations: 4 kB / 0.8388 GB, free: 8.336 MB / 0.6511 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.292e-06/0.7011, allocations: 7.984 kB / 0.8388 GB, free: 8.328 MB / 0.6511 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001518/0.7026, allocations: 0.9124 MB / 0.8397 GB, free: 7.367 MB / 0.6511 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006785/0.7033, allocations: 262.5 kB / 0.8399 GB, free: 7.109 MB / 0.6511 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001658/0.7035, allocations: 12 kB / 0.8399 GB, free: 7.098 MB / 0.6511 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005014/0.704, allocations: 16 kB / 0.8399 GB, free: 7.082 MB / 0.6511 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001128/0.7041, allocations: 40.42 kB / 0.84 GB, free: 7.043 MB / 0.6511 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.489e-05/0.7041, allocations: 16 kB / 0.84 GB, free: 7.027 MB / 0.6511 GB
Notification: Performance of sorting global known variables: time 0.001961/0.7061, allocations: 1.289 MB / 0.8413 GB, free: 5.719 MB / 0.6511 GB
Notification: Performance of sort global known variables: time 9e-08/0.7061, allocations: 0 / 0.8413 GB, free: 5.719 MB / 0.6511 GB
Notification: Performance of remove unused functions: time 0.001258/0.7073, allocations: 0.5142 MB / 0.8418 GB, free: 5.203 MB / 0.6511 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.002919/0.7103, allocations: 0.5663 MB / 0.8423 GB, free: 4.832 MB / 0.6511 GB
Notification: Performance of simCode: created initialization part: time 0.0008018/0.7111, allocations: 339.8 kB / 0.8426 GB, free: 4.5 MB / 0.6511 GB
Notification: Performance of simCode: created event and clocks part: time 2.614e-06/0.7111, allocations: 0 / 0.8426 GB, free: 4.5 MB / 0.6511 GB
Notification: Performance of simCode: created simulation system equations: time 0.0003905/0.7115, allocations: 199.3 kB / 0.8428 GB, free: 4.297 MB / 0.6511 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00203/0.7135, allocations: 455.9 kB / 0.8433 GB, free: 3.879 MB / 0.6511 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006045/0.7195, allocations: 3.54 MB / 0.8467 GB, free: 264 kB / 0.6511 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.184/0.9035, allocations: 0.9959 MB / 0.8477 GB, free: 239.3 MB / 0.6511 GB
Notification: Performance of simCode: alias equations: time 0.0009148/0.9045, allocations: 304 kB / 0.848 GB, free: 239.3 MB / 0.6511 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002658/0.9047, allocations: 120.1 kB / 0.8481 GB, free: 239.3 MB / 0.6511 GB
Notification: Performance of SimCode: time 1.823e-06/0.9047, allocations: 0 / 0.8481 GB, free: 239.3 MB / 0.6511 GB
Notification: Performance of Templates: time 0.03845/0.9432, allocations: 20.7 MB / 0.8683 GB, free: 234.9 MB / 0.6511 GB
"
[Timeout remaining time 659]
make -j1 -f BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.OneWayFlow.makefile [Timeout 660]
(rm -f BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.OneWayFlow.pipe ; mkfifo BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.OneWayFlow.pipe ; head -c 1048576 < BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.OneWayFlow.pipe >> ../files/BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.OneWayFlow.sim & ./BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.OneWayFlow -abortSlowSimulation -alarm=240  -lv LOG_STATS > BuildingSystems_BuildingSystems.Airflow.Multizone.Validation.OneWayFlow.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 7.399475330021232]