Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Fluid.Examples.Performance.Example4.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001555/0.001555, allocations: 113.9 kB / 18.94 MB, free: 2.93 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.00169/0.00169, allocations: 191.1 kB / 22.23 MB, free: 5.102 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.321/1.321, allocations: 225.6 MB / 251 MB, free: 13.03 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.1-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.1-master/package.mo): time 2.821/2.821, allocations: 0.4893 GB / 0.7884 GB, free: 33.21 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 12.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.1-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(Buildings.Fluid.Examples.Performance.Example4,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|res.m_flow",fileNamePrefix="Buildings_latest_Buildings.Fluid.Examples.Performance.Example4")
translateModel(Buildings.Fluid.Examples.Performance.Example4,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|res.m_flow",fileNamePrefix="Buildings_latest_Buildings.Fluid.Examples.Performance.Example4") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 0.001877/0.001877, allocations: 56.97 kB / 1.145 GB, free: 30.62 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2837/0.2856, allocations: 174.9 MB / 1.316 GB, free: 9.703 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.Examples.Performance.Example4): time 0.2553/0.5409, allocations: 239.3 MB / 1.55 GB, free: 9.539 MB / 1.248 GB
Notification: Performance of NFInst.instExpressions: time 0.02161/0.5625, allocations: 15.93 MB / 1.565 GB, free: 9.57 MB / 1.264 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002146/0.5646, allocations: 87.25 kB / 1.565 GB, free: 9.484 MB / 1.264 GB
Notification: Performance of NFTyping.typeComponents: time 0.002566/0.5672, allocations: 1.084 MB / 1.566 GB, free: 8.395 MB / 1.264 GB
Notification: Performance of NFTyping.typeBindings: time 0.007429/0.5746, allocations: 3.139 MB / 1.569 GB, free: 5.242 MB / 1.264 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004031/0.5786, allocations: 1.726 MB / 1.571 GB, free: 3.527 MB / 1.264 GB
Notification: Performance of NFFlatten.flatten: time 0.004491/0.5831, allocations: 3.465 MB / 1.575 GB, free: 52 kB / 1.264 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001362/0.5845, allocations: 0.999 MB / 1.575 GB, free: 15.01 MB / 1.279 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006149/0.5906, allocations: 2.891 MB / 1.578 GB, free: 12.12 MB / 1.279 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001888/0.5925, allocations: 1.124 MB / 1.579 GB, free: 11 MB / 1.279 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001921/0.5927, allocations: 116 kB / 1.58 GB, free: 10.89 MB / 1.279 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005371/0.5981, allocations: 3.27 MB / 1.583 GB, free: 7.613 MB / 1.279 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004823/0.5986, allocations: 490.2 kB / 1.583 GB, free: 7.133 MB / 1.279 GB
Notification: Performance of NFVerifyModel.verify: time 0.0008817/0.5995, allocations: 0.8123 MB / 1.584 GB, free: 6.316 MB / 1.279 GB
Notification: Performance of NFConvertDAE.convert: time 0.004304/0.6038, allocations: 2.978 MB / 1.587 GB, free: 3.328 MB / 1.279 GB
Notification: Performance of FrontEnd - DAE generated: time 3.687e-06/0.6038, allocations: 0 / 1.587 GB, free: 3.328 MB / 1.279 GB
Notification: Performance of FrontEnd: time 1.032e-06/0.6038, allocations: 0 / 1.587 GB, free: 3.328 MB / 1.279 GB
Notification: Performance of Transformations before backend: time 2.017e-05/0.6038, allocations: 4 kB / 1.587 GB, free: 3.324 MB / 1.279 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 251
 * Number of variables: 251
Notification: Performance of Generate backend data structure: time 0.003364/0.6072, allocations: 1.993 MB / 1.589 GB, free: 1.289 MB / 1.279 GB
Notification: Performance of prepare preOptimizeDAE: time 3.172e-05/0.6072, allocations: 8.031 kB / 1.589 GB, free: 1.281 MB / 1.279 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002608/0.6098, allocations: 0.5024 MB / 1.589 GB, free: 0.7773 MB / 1.279 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002185/0.612, allocations: 1.101 MB / 1.59 GB, free: 15.62 MB / 1.295 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.325e-05/0.6121, allocations: 82 kB / 1.59 GB, free: 15.54 MB / 1.295 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001438/0.6122, allocations: 96 kB / 1.591 GB, free: 15.45 MB / 1.295 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002373/0.6146, allocations: 1.35 MB / 1.592 GB, free: 13.98 MB / 1.295 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.402e-05/0.6146, allocations: 7.938 kB / 1.592 GB, free: 13.97 MB / 1.295 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001048/0.6147, allocations: 44 kB / 1.592 GB, free: 13.93 MB / 1.295 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.214e-05/0.6148, allocations: 41.98 kB / 1.592 GB, free: 13.89 MB / 1.295 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002429/0.6172, allocations: 1.065 MB / 1.593 GB, free: 12.82 MB / 1.295 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.008241/0.6254, allocations: 7.385 MB / 1.6 GB, free: 4.938 MB / 1.295 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001591/0.627, allocations: 0.5205 MB / 1.601 GB, free: 4.41 MB / 1.295 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0006206/0.6276, allocations: 253.7 kB / 1.601 GB, free: 4.16 MB / 1.295 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002784/0.6279, allocations: 60.17 kB / 1.601 GB, free: 4.102 MB / 1.295 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.786e-05/0.628, allocations: 34.84 kB / 1.601 GB, free: 4.059 MB / 1.295 GB
Notification: Performance of pre-optimization done (n=33): time 1.844e-06/0.628, allocations: 0 / 1.601 GB, free: 4.059 MB / 1.295 GB
Notification: Performance of matching and sorting (n=33): time 0.002349/0.6303, allocations: 0.846 MB / 1.602 GB, free: 3.207 MB / 1.295 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.271e-05/0.6303, allocations: 55.41 kB / 1.602 GB, free: 3.141 MB / 1.295 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001862/0.6322, allocations: 1.403 MB / 1.603 GB, free: 1.727 MB / 1.295 GB
Notification: Performance of collectPreVariables (initialization): time 5.849e-05/0.6323, allocations: 33.7 kB / 1.603 GB, free: 1.688 MB / 1.295 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000602/0.6329, allocations: 0.7119 MB / 1.604 GB, free: 0.9727 MB / 1.295 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001349/0.633, allocations: 127 kB / 1.604 GB, free: 0.8477 MB / 1.295 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002584/0.6333, allocations: 162.5 kB / 1.604 GB, free: 0.6836 MB / 1.295 GB
Notification: Performance of setup shared object (initialization): time 0.0001512/0.6334, allocations: 334 kB / 1.605 GB, free: 360 kB / 1.295 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0008371/0.6342, allocations: 316.6 kB / 1.605 GB, free: 40 kB / 1.295 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.5372/1.171, allocations: 0.6789 MB / 1.606 GB, free: 289.6 MB / 1.295 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001636/1.173, allocations: 0.9352 MB / 1.607 GB, free: 289.2 MB / 1.295 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.678e-06/1.173, allocations: 3.594 kB / 1.607 GB, free: 289.2 MB / 1.295 GB
Notification: Performance of matching and sorting (n=88) (initialization): time 0.002453/1.176, allocations: 1.054 MB / 1.608 GB, free: 289.1 MB / 1.295 GB
Notification: Performance of prepare postOptimizeDAE: time 2.441e-05/1.176, allocations: 13.27 kB / 1.608 GB, free: 289.1 MB / 1.295 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.882e-05/1.176, allocations: 12.77 kB / 1.608 GB, free: 289.1 MB / 1.295 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.313e-05/1.176, allocations: 19.56 kB / 1.608 GB, free: 289.1 MB / 1.295 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008139/1.176, allocations: 207.6 kB / 1.608 GB, free: 289.1 MB / 1.295 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.291e-05/1.176, allocations: 22.52 kB / 1.608 GB, free: 289.1 MB / 1.295 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00125/1.178, allocations: 182.8 kB / 1.608 GB, free: 289.1 MB / 1.295 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.205e-05/1.178, allocations: 44.67 kB / 1.608 GB, free: 289.1 MB / 1.295 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001126/1.179, allocations: 0.5668 MB / 1.609 GB, free: 289 MB / 1.295 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0008944/1.18, allocations: 0.6858 MB / 1.609 GB, free: 288.5 MB / 1.295 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.00149/1.181, allocations: 0.9309 MB / 1.61 GB, free: 288 MB / 1.295 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 5.491e-06/1.181, allocations: 3.562 kB / 1.61 GB, free: 288 MB / 1.295 GB
Notification: Performance of matching and sorting (n=88) (initialization_lambda0): time 0.002025/1.183, allocations: 1.051 MB / 1.611 GB, free: 287.7 MB / 1.295 GB
Notification: Performance of prepare postOptimizeDAE: time 1.288e-05/1.183, allocations: 12.78 kB / 1.611 GB, free: 287.7 MB / 1.295 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.028e-05/1.183, allocations: 12.91 kB / 1.611 GB, free: 287.7 MB / 1.295 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.176e-05/1.183, allocations: 15.53 kB / 1.611 GB, free: 287.7 MB / 1.295 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007427/1.184, allocations: 202.3 kB / 1.611 GB, free: 287.7 MB / 1.295 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.565e-05/1.184, allocations: 26.75 kB / 1.611 GB, free: 287.7 MB / 1.295 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001164/1.185, allocations: 142 kB / 1.612 GB, free: 287.7 MB / 1.295 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.988e-05/1.185, allocations: 42.89 kB / 1.612 GB, free: 287.7 MB / 1.295 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 52
 * 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 (88):
 * Single equations (assignments): 88
 * 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.0002169/1.186, allocations: 236 kB / 1.612 GB, free: 287.5 MB / 1.295 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00016/1.186, allocations: 152.1 kB / 1.612 GB, free: 287.4 MB / 1.295 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001506/1.187, allocations: 0.6426 MB / 1.613 GB, free: 287.2 MB / 1.295 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.909e-06/1.187, allocations: 8.609 kB / 1.613 GB, free: 287.1 MB / 1.295 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.717e-06/1.187, allocations: 0 / 1.613 GB, free: 287.1 MB / 1.295 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.78e-06/1.187, allocations: 1.922 kB / 1.613 GB, free: 287.1 MB / 1.295 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003107/1.19, allocations: 1.862 MB / 1.614 GB, free: 286.3 MB / 1.295 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.147e-06/1.19, allocations: 2.219 kB / 1.614 GB, free: 286.3 MB / 1.295 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004655/1.191, allocations: 95.77 kB / 1.615 GB, free: 286.3 MB / 1.295 GB
Notification: Performance of postOpt tearingSystem (simulation): time 6.372e-06/1.191, allocations: 6.562 kB / 1.615 GB, free: 286.3 MB / 1.295 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.158e-05/1.191, allocations: 12.33 kB / 1.615 GB, free: 286.3 MB / 1.295 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 7.354e-06/1.191, allocations: 10.3 kB / 1.615 GB, free: 286.2 MB / 1.295 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.486e-06/1.191, allocations: 6.031 kB / 1.615 GB, free: 286.2 MB / 1.295 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001572/1.192, allocations: 0.8127 MB / 1.615 GB, free: 285.8 MB / 1.295 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005387/1.193, allocations: 218.1 kB / 1.616 GB, free: 285.7 MB / 1.295 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001864/1.193, allocations: 11.33 kB / 1.616 GB, free: 285.7 MB / 1.295 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009856/1.194, allocations: 67.31 kB / 1.616 GB, free: 285.7 MB / 1.295 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.504e-05/1.194, allocations: 38.33 kB / 1.616 GB, free: 285.6 MB / 1.295 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 5.573e-05/1.194, allocations: 26.64 kB / 1.616 GB, free: 285.6 MB / 1.295 GB
Notification: Performance of sorting global known variables: time 0.001572/1.196, allocations: 1.008 MB / 1.617 GB, free: 285.3 MB / 1.295 GB
Notification: Performance of sort global known variables: time 7e-08/1.196, allocations: 0.6875 kB / 1.617 GB, free: 285.3 MB / 1.295 GB
Notification: Performance of remove unused functions: time 0.001979/1.198, allocations: 0.5838 MB / 1.617 GB, free: 285.2 MB / 1.295 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 16
 * 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 simulation (32):
 * Single equations (assignments): 32
 * 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.0005594/1.198, allocations: 264.8 kB / 1.618 GB, free: 285.1 MB / 1.295 GB
Notification: Performance of simCode: created initialization part: time 0.002732/1.201, allocations: 1.091 MB / 1.619 GB, free: 284.8 MB / 1.295 GB
Notification: Performance of simCode: created event and clocks part: time 3.757e-06/1.201, allocations: 1.188 kB / 1.619 GB, free: 284.8 MB / 1.295 GB
Notification: Performance of simCode: created simulation system equations: time 0.0006039/1.202, allocations: 247.1 kB / 1.619 GB, free: 284.7 MB / 1.295 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002969/1.205, allocations: 0.755 MB / 1.62 GB, free: 284.5 MB / 1.295 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004148/1.209, allocations: 2.273 MB / 1.622 GB, free: 283.3 MB / 1.295 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.000604/1.209, allocations: 1.184 MB / 1.623 GB, free: 282.6 MB / 1.295 GB
Notification: Performance of simCode: alias equations: time 0.001185/1.211, allocations: 386.8 kB / 1.623 GB, free: 282.5 MB / 1.295 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002279/1.211, allocations: 94.66 kB / 1.623 GB, free: 282.5 MB / 1.295 GB
Notification: Performance of SimCode: time 6.01e-07/1.211, allocations: 0 / 1.623 GB, free: 282.5 MB / 1.295 GB
Notification: Performance of Templates: time 0.03285/1.244, allocations: 25.77 MB / 1.649 GB, free: 268.8 MB / 1.295 GB
"
[Timeout remaining time 299]
make -j1 -f Buildings_latest_Buildings.Fluid.Examples.Performance.Example4.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.Fluid.Examples.Performance.Example4.pipe ; mkfifo Buildings_latest_Buildings.Fluid.Examples.Performance.Example4.pipe ; head -c 1048576 < Buildings_latest_Buildings.Fluid.Examples.Performance.Example4.pipe >> ../files/Buildings_latest_Buildings.Fluid.Examples.Performance.Example4.sim & ./Buildings_latest_Buildings.Fluid.Examples.Performance.Example4  -abortSlowSimulation -alarm=800  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Fluid.Examples.Performance.Example4.pipe 2>&1) [Timeout 800]
diffSimulationResults("Buildings_latest_Buildings.Fluid.Examples.Performance.Example4_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_Fluid_Examples_Performance_Example4.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_Buildings.Fluid.Examples.Performance.Example4.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
Reference file matches
[Calling sys.exit(0), Time elapsed: 9.235288878902793]