Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_9_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)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.2-maint.9.1.x/package.mo", uses=false)
Using package Buildings with version 9.1.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.2-maint.9.1.x/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=5000,variableFilter="time|res.m_flow",fileNamePrefix="Buildings_9_Buildings.Fluid.Examples.Performance.Example4")
translateModel(Buildings.Fluid.Examples.Performance.Example4,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|res.m_flow",fileNamePrefix="Buildings_9_Buildings.Fluid.Examples.Performance.Example4")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001657/0.001657, allocations: 115 kB / 16.37 MB, free: 5.539 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001795/0.001795, allocations: 189.4 kB / 17.3 MB, free: 5.18 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.339/1.339, allocations: 222.9 MB / 241 MB, free: 15.15 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.2-maint.9.1.x/package.mo): time 2.206/2.206, allocations: 390.3 MB / 0.6655 GB, free: 0.6172 MB / 0.5294 GB
Notification: Performance of FrontEnd - loaded program: time 0.001527/0.001527, allocations: 71.22 kB / 0.814 GB, free: 32.78 MB / 0.6231 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3093/0.3108, allocations: 140.4 MB / 0.9511 GB, free: 8.098 MB / 0.7325 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.Examples.Performance.Example4): time 0.8413/1.152, allocations: 237.6 MB / 1.183 GB, free: 14.15 MB / 0.8731 GB
Notification: Performance of NFInst.instExpressions: time 0.02077/1.173, allocations: 15.78 MB / 1.199 GB, free: 3.719 MB / 0.8731 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002549/1.176, allocations: 62.12 kB / 1.199 GB, free: 3.695 MB / 0.8731 GB
Notification: Performance of NFTyping.typeComponents: time 0.002651/1.178, allocations: 1.038 MB / 1.2 GB, free: 3.062 MB / 0.8731 GB
Notification: Performance of NFTyping.typeBindings: time 0.007753/1.186, allocations: 2.984 MB / 1.203 GB, free: 0.832 MB / 0.8731 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004697/1.191, allocations: 1.67 MB / 1.204 GB, free: 15.59 MB / 0.8887 GB
Notification: Performance of NFFlatten.flatten: time 0.004849/1.196, allocations: 3.445 MB / 1.208 GB, free: 13.59 MB / 0.8887 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001766/1.197, allocations: 1.28 MB / 1.209 GB, free: 12.79 MB / 0.8887 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006005/1.203, allocations: 2.502 MB / 1.211 GB, free: 11.2 MB / 0.8887 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002112/1.206, allocations: 1.084 MB / 1.212 GB, free: 10.57 MB / 0.8887 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002067/1.206, allocations: 122 kB / 1.212 GB, free: 10.57 MB / 0.8887 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006503/1.212, allocations: 3.09 MB / 1.215 GB, free: 8.863 MB / 0.8887 GB
Notification: Performance of NFScalarize.scalarize: time 0.0007041/1.213, allocations: 492 kB / 1.216 GB, free: 8.516 MB / 0.8887 GB
Notification: Performance of NFVerifyModel.verify: time 0.001246/1.214, allocations: 0.8045 MB / 1.217 GB, free: 7.992 MB / 0.8887 GB
Notification: Performance of NFConvertDAE.convert: time 0.005188/1.22, allocations: 2.963 MB / 1.22 GB, free: 6.133 MB / 0.8887 GB
Notification: Performance of FrontEnd - DAE generated: time 5.57e-06/1.22, allocations: 2.344 kB / 1.22 GB, free: 6.133 MB / 0.8887 GB
Notification: Performance of FrontEnd: time 1.983e-06/1.22, allocations: 0 / 1.22 GB, free: 6.133 MB / 0.8887 GB
Notification: Performance of Transformations before backend: time 2.084e-05/1.22, allocations: 0 / 1.22 GB, free: 6.133 MB / 0.8887 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.0036/1.223, allocations: 2.002 MB / 1.222 GB, free: 4.629 MB / 0.8887 GB
Notification: Performance of prepare preOptimizeDAE: time 3.705e-05/1.223, allocations: 10.62 kB / 1.222 GB, free: 4.629 MB / 0.8887 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002739/1.226, allocations: 0.4953 MB / 1.222 GB, free: 4.398 MB / 0.8887 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002349/1.228, allocations: 1.172 MB / 1.223 GB, free: 3.484 MB / 0.8887 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 6.63e-05/1.228, allocations: 77.25 kB / 1.223 GB, free: 3.453 MB / 0.8887 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001366/1.229, allocations: 104.8 kB / 1.223 GB, free: 3.414 MB / 0.8887 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002221/1.231, allocations: 1.344 MB / 1.225 GB, free: 2.461 MB / 0.8887 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.507e-05/1.231, allocations: 6.719 kB / 1.225 GB, free: 2.457 MB / 0.8887 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.357e-05/1.231, allocations: 40.47 kB / 1.225 GB, free: 2.43 MB / 0.8887 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.868e-05/1.231, allocations: 48.05 kB / 1.225 GB, free: 2.422 MB / 0.8887 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00222/1.233, allocations: 1.048 MB / 1.226 GB, free: 1.906 MB / 0.8887 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.007779/1.241, allocations: 7.386 MB / 1.233 GB, free: 11.13 MB / 0.9044 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001426/1.242, allocations: 0.5387 MB / 1.233 GB, free: 10.86 MB / 0.9044 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0005768/1.243, allocations: 271 kB / 1.234 GB, free: 10.73 MB / 0.9044 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003915/1.243, allocations: 280.4 kB / 1.234 GB, free: 10.43 MB / 0.9044 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.649e-05/1.243, allocations: 37.03 kB / 1.234 GB, free: 10.39 MB / 0.9044 GB
Notification: Performance of pre-optimization done (n=33): time 2.013e-06/1.243, allocations: 3 kB / 1.234 GB, free: 10.39 MB / 0.9044 GB
Notification: Performance of matching and sorting (n=33): time 0.002201/1.246, allocations: 0.8397 MB / 1.235 GB, free: 9.973 MB / 0.9044 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.399e-05/1.246, allocations: 56.69 kB / 1.235 GB, free: 9.91 MB / 0.9044 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001694/1.247, allocations: 1.391 MB / 1.236 GB, free: 9.156 MB / 0.9044 GB
Notification: Performance of collectPreVariables (initialization): time 6.699e-05/1.248, allocations: 34.02 kB / 1.236 GB, free: 9.121 MB / 0.9044 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000513/1.248, allocations: 0.7006 MB / 1.237 GB, free: 8.5 MB / 0.9044 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001029/1.248, allocations: 124.2 kB / 1.237 GB, free: 8.395 MB / 0.9044 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000235/1.248, allocations: 166 kB / 1.237 GB, free: 8.293 MB / 0.9044 GB
Notification: Performance of setup shared object (initialization): time 0.0001218/1.249, allocations: 329.2 kB / 1.238 GB, free: 7.973 MB / 0.9044 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007535/1.249, allocations: 313.4 kB / 1.238 GB, free: 7.82 MB / 0.9044 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001017/1.25, allocations: 0.6948 MB / 1.239 GB, free: 7.156 MB / 0.9044 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001964/1.252, allocations: 0.9281 MB / 1.239 GB, free: 6.395 MB / 0.9044 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.988e-06/1.252, allocations: 2.094 kB / 1.239 GB, free: 6.395 MB / 0.9044 GB
Notification: Performance of matching and sorting (n=88) (initialization): time 0.002475/1.255, allocations: 1.05 MB / 1.241 GB, free: 5.867 MB / 0.9044 GB
Notification: Performance of prepare postOptimizeDAE: time 2.207e-05/1.255, allocations: 10.03 kB / 1.241 GB, free: 5.867 MB / 0.9044 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.312e-05/1.255, allocations: 10.78 kB / 1.241 GB, free: 5.867 MB / 0.9044 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.501e-05/1.255, allocations: 17.38 kB / 1.241 GB, free: 5.867 MB / 0.9044 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.000845/1.256, allocations: 209.3 kB / 1.241 GB, free: 5.805 MB / 0.9044 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.543e-05/1.256, allocations: 24.45 kB / 1.241 GB, free: 5.797 MB / 0.9044 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00139/1.257, allocations: 178.7 kB / 1.241 GB, free: 5.695 MB / 0.9044 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.231e-05/1.257, allocations: 42.33 kB / 1.241 GB, free: 5.688 MB / 0.9044 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001241/1.259, allocations: 0.5694 MB / 1.242 GB, free: 5.348 MB / 0.9044 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001032/1.26, allocations: 0.6865 MB / 1.242 GB, free: 4.688 MB / 0.9044 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.001571/1.261, allocations: 0.9257 MB / 1.243 GB, free: 3.926 MB / 0.9044 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 5.961e-06/1.261, allocations: 5.062 kB / 1.243 GB, free: 3.926 MB / 0.9044 GB
Notification: Performance of matching and sorting (n=88) (initialization_lambda0): time 0.002254/1.263, allocations: 1.046 MB / 1.244 GB, free: 3.398 MB / 0.9044 GB
Notification: Performance of prepare postOptimizeDAE: time 1.921e-05/1.263, allocations: 10.38 kB / 1.244 GB, free: 3.398 MB / 0.9044 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.01e-05/1.263, allocations: 20.59 kB / 1.244 GB, free: 3.391 MB / 0.9044 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.335e-05/1.263, allocations: 14.75 kB / 1.244 GB, free: 3.391 MB / 0.9044 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.000819/1.264, allocations: 202.5 kB / 1.244 GB, free: 3.219 MB / 0.9044 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.079e-05/1.264, allocations: 27.92 kB / 1.244 GB, free: 3.191 MB / 0.9044 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001331/1.266, allocations: 135.5 kB / 1.245 GB, free: 3.059 MB / 0.9044 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.251e-05/1.266, allocations: 39.98 kB / 1.245 GB, free: 3.02 MB / 0.9044 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.0002604/1.266, allocations: 236.4 kB / 1.245 GB, free: 2.781 MB / 0.9044 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002179/1.266, allocations: 138.3 kB / 1.245 GB, free: 2.645 MB / 0.9044 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001645/1.268, allocations: 0.652 MB / 1.246 GB, free: 1.977 MB / 0.9044 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.442e-06/1.268, allocations: 4 kB / 1.246 GB, free: 1.973 MB / 0.9044 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.839e-06/1.268, allocations: 0 / 1.246 GB, free: 1.973 MB / 0.9044 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.72e-06/1.268, allocations: 3.984 kB / 1.246 GB, free: 1.969 MB / 0.9044 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0035/1.271, allocations: 1.893 MB / 1.247 GB, free: 15.96 MB / 0.92 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.231e-06/1.271, allocations: 4 kB / 1.247 GB, free: 15.96 MB / 0.92 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004991/1.272, allocations: 88.02 kB / 1.248 GB, free: 15.88 MB / 0.92 GB
Notification: Performance of postOpt tearingSystem (simulation): time 6.973e-06/1.272, allocations: 8 kB / 1.248 GB, free: 15.87 MB / 0.92 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.761e-05/1.272, allocations: 12 kB / 1.248 GB, free: 15.86 MB / 0.92 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 6.973e-06/1.272, allocations: 11.92 kB / 1.248 GB, free: 15.84 MB / 0.92 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.777e-06/1.272, allocations: 4 kB / 1.248 GB, free: 15.84 MB / 0.92 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001482/1.274, allocations: 0.8073 MB / 1.248 GB, free: 14.96 MB / 0.92 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005273/1.274, allocations: 295.1 kB / 1.249 GB, free: 14.66 MB / 0.92 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001857/1.274, allocations: 12 kB / 1.249 GB, free: 14.64 MB / 0.92 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001015/1.275, allocations: 71.86 kB / 1.249 GB, free: 14.57 MB / 0.92 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.482e-05/1.275, allocations: 31.89 kB / 1.249 GB, free: 14.54 MB / 0.92 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 5.03e-05/1.275, allocations: 23.98 kB / 1.249 GB, free: 14.52 MB / 0.92 GB
Notification: Performance of sorting global known variables: time 0.001384/1.277, allocations: 1.005 MB / 1.25 GB, free: 13.52 MB / 0.92 GB
Notification: Performance of sort global known variables: time 1.7e-07/1.277, allocations: 4 kB / 1.25 GB, free: 13.52 MB / 0.92 GB
Notification: Performance of remove unused functions: time 0.001782/1.279, allocations: 0.5735 MB / 1.25 GB, free: 12.94 MB / 0.92 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.0005534/1.279, allocations: 273.3 kB / 1.251 GB, free: 12.67 MB / 0.92 GB
Notification: Performance of simCode: created initialization part: time 0.002653/1.282, allocations: 1.12 MB / 1.252 GB, free: 11.55 MB / 0.92 GB
Notification: Performance of simCode: created event and clocks part: time 4.89e-06/1.282, allocations: 4.922 kB / 1.252 GB, free: 11.55 MB / 0.92 GB
Notification: Performance of simCode: created simulation system equations: time 0.0006229/1.283, allocations: 255.2 kB / 1.252 GB, free: 11.3 MB / 0.92 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003046/1.286, allocations: 0.751 MB / 1.253 GB, free: 10.55 MB / 0.92 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004724/1.29, allocations: 2.234 MB / 1.255 GB, free: 8.242 MB / 0.92 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007895/1.291, allocations: 1.186 MB / 1.256 GB, free: 7.027 MB / 0.92 GB
Notification: Performance of simCode: alias equations: time 0.001326/1.292, allocations: 387.8 kB / 1.256 GB, free: 6.645 MB / 0.92 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002509/1.293, allocations: 95.42 kB / 1.256 GB, free: 6.551 MB / 0.92 GB
Notification: Performance of SimCode: time 9.12e-07/1.293, allocations: 0 / 1.256 GB, free: 6.551 MB / 0.92 GB
Notification: Performance of Templates: time 0.04296/1.336, allocations: 24.99 MB / 1.281 GB, free: 13.9 MB / 0.9512 GB
make -j1 -f Buildings_9_Buildings.Fluid.Examples.Performance.Example4.makefile
(rm -f Buildings_9_Buildings.Fluid.Examples.Performance.Example4.pipe ; mkfifo Buildings_9_Buildings.Fluid.Examples.Performance.Example4.pipe ; head -c 1048576 < Buildings_9_Buildings.Fluid.Examples.Performance.Example4.pipe >> ../files/Buildings_9_Buildings.Fluid.Examples.Performance.Example4.sim & ./Buildings_9_Buildings.Fluid.Examples.Performance.Example4  -abortSlowSimulation -alarm=480 -s cvode -emit_protected -lv LOG_STATS > Buildings_9_Buildings.Fluid.Examples.Performance.Example4.pipe 2>&1)
diffSimulationResults("Buildings_9_Buildings.Fluid.Examples.Performance.Example4_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_9.1.x/Buildings_Fluid_Examples_Performance_Example4.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_9_Buildings.Fluid.Examples.Performance.Example4.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches