Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Examples.Tutorial.Boiler.System3.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 12.0.0-master/package.mo", uses=false)
Using package Buildings with version 12.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.0-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.Examples.Tutorial.Boiler.System3,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|vol.T_start|vol.T|TOut.T|boi.vol.T",fileNamePrefix="Buildings_latest_Buildings.Examples.Tutorial.Boiler.System3")
translateModel(Buildings.Examples.Tutorial.Boiler.System3,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|vol.T_start|vol.T|TOut.T|boi.vol.T",fileNamePrefix="Buildings_latest_Buildings.Examples.Tutorial.Boiler.System3")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001905/0.001905, allocations: 115.2 kB / 16.42 MB, free: 6.457 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.00205/0.00205, allocations: 183.9 kB / 17.35 MB, free: 5.734 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.432/1.432, allocations: 222.9 MB / 241.1 MB, free: 15.14 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.0.0-master/package.mo): time 2.834/2.834, allocations: 472.7 MB / 0.746 GB, free: 436 kB / 0.6075 GB
Notification: Performance of FrontEnd - loaded program: time 0.001428/0.001428, allocations: 59.91 kB / 1.07 GB, free: 9.543 MB / 0.8419 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.051/1.053, allocations: 159.9 MB / 1.226 GB, free: 13.48 MB / 0.9512 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.Tutorial.Boiler.System3): time 1.669/2.722, allocations: 0.5096 GB / 1.736 GB, free: 41.96 MB / 1.279 GB
Notification: Performance of NFInst.instExpressions: time 0.1005/2.823, allocations: 67.95 MB / 1.802 GB, free: 9.781 MB / 1.279 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02232/2.845, allocations: 414.8 kB / 1.803 GB, free: 9.523 MB / 1.279 GB
Notification: Performance of NFTyping.typeComponents: time 0.02299/2.868, allocations: 5.77 MB / 1.808 GB, free: 5.348 MB / 1.279 GB
Notification: Performance of NFTyping.typeBindings: time 0.05708/2.925, allocations: 14.11 MB / 1.822 GB, free: 7.375 MB / 1.295 GB
Notification: Performance of NFTyping.typeClassSections: time 0.02999/2.955, allocations: 9.545 MB / 1.831 GB, free: 13.91 MB / 1.311 GB
Notification: Performance of NFFlatten.flatten: time 0.06097/3.016, allocations: 36.65 MB / 1.867 GB, free: 9.176 MB / 1.342 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02709/3.043, allocations: 10.99 MB / 1.878 GB, free: 14.06 MB / 1.357 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.035/3.078, allocations: 11.84 MB / 1.889 GB, free: 2.223 MB / 1.357 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01948/3.098, allocations: 8.718 MB / 1.898 GB, free: 9.469 MB / 1.373 GB
Notification: Performance of NFPackage.collectConstants: time 0.00557/3.104, allocations: 1.082 MB / 1.899 GB, free: 8.387 MB / 1.373 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02081/3.124, allocations: 6.104 MB / 1.905 GB, free: 2.273 MB / 1.373 GB
Notification: Performance of NFScalarize.scalarize: time 0.006793/3.131, allocations: 3.294 MB / 1.908 GB, free: 14.97 MB / 1.389 GB
Notification: Performance of NFVerifyModel.verify: time 0.01791/3.149, allocations: 6.606 MB / 1.915 GB, free: 8.332 MB / 1.389 GB
Notification: Performance of NFConvertDAE.convert: time 0.04066/3.19, allocations: 19.04 MB / 1.933 GB, free: 5.227 MB / 1.404 GB
Notification: Performance of FrontEnd - DAE generated: time 7.794e-06/3.19, allocations: 0 / 1.933 GB, free: 5.227 MB / 1.404 GB
Notification: Performance of FrontEnd: time 2.024e-06/3.19, allocations: 4 kB / 1.933 GB, free: 5.223 MB / 1.404 GB
Notification: Performance of Transformations before backend: time 0.000803/3.191, allocations: 0 / 1.933 GB, free: 5.223 MB / 1.404 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1609
 * Number of variables: 1609
Notification: Performance of Generate backend data structure: time 0.03943/3.23, allocations: 13.33 MB / 1.946 GB, free: 7.738 MB / 1.42 GB
Notification: Performance of prepare preOptimizeDAE: time 5.615e-05/3.23, allocations: 11.98 kB / 1.946 GB, free: 7.727 MB / 1.42 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01379/3.244, allocations: 2.147 MB / 1.948 GB, free: 5.57 MB / 1.42 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.03056/3.275, allocations: 11.91 MB / 1.96 GB, free: 9.582 MB / 1.436 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005592/3.275, allocations: 0.4927 MB / 1.961 GB, free: 9.086 MB / 1.436 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002398/3.278, allocations: 0.664 MB / 1.961 GB, free: 8.422 MB / 1.436 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02203/3.3, allocations: 7.593 MB / 1.969 GB, free: 0.5469 MB / 1.436 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003497/3.3, allocations: 19.83 kB / 1.969 GB, free: 0.5273 MB / 1.436 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001941/3.302, allocations: 264 kB / 1.969 GB, free: 276 kB / 1.436 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.000269/3.302, allocations: 267.9 kB / 1.969 GB, free: 8 kB / 1.436 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0159/3.318, allocations: 5.902 MB / 1.975 GB, free: 10.1 MB / 1.451 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.07097/3.389, allocations: 38.91 MB / 2.013 GB, free: 464 kB / 1.482 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.007226/3.397, allocations: 3.861 MB / 2.017 GB, free: 12.49 MB / 1.498 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004071/3.401, allocations: 2.057 MB / 2.019 GB, free: 10.43 MB / 1.498 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 evalFunc (simulation): time 0.02646/3.427, allocations: 9.63 MB / 2.028 GB, free: 316 kB / 1.498 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004855/3.432, allocations: 2.038 MB / 2.03 GB, free: 14.23 MB / 1.514 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.004729/3.437, allocations: 337.4 kB / 2.03 GB, free: 13.89 MB / 1.514 GB
Notification: Performance of pre-optimization done (n=319): time 2.152e-05/3.437, allocations: 0 / 2.03 GB, free: 13.89 MB / 1.514 GB
Notification: Performance of matching and sorting (n=327): time 0.03999/3.477, allocations: 12.93 MB / 2.043 GB, free: 0.6953 MB / 1.514 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.184e-05/3.477, allocations: 111.7 kB / 2.043 GB, free: 0.5664 MB / 1.514 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.02198/3.499, allocations: 8.94 MB / 2.052 GB, free: 7.664 MB / 1.529 GB
Notification: Performance of collectPreVariables (initialization): time 0.001362/3.5, allocations: 121.7 kB / 2.052 GB, free: 7.539 MB / 1.529 GB
Notification: Performance of collectInitialEqns (initialization): time 0.006714/3.507, allocations: 4.974 MB / 2.057 GB, free: 2.539 MB / 1.529 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002218/3.509, allocations: 1.22 MB / 2.058 GB, free: 1.309 MB / 1.529 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004414/3.514, allocations: 1.21 MB / 2.059 GB, free: 88 kB / 1.529 GB
Notification: Performance of setup shared object (initialization): time 0.0001195/3.514, allocations: 301.1 kB / 2.059 GB, free: 15.79 MB / 1.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005382/3.519, allocations: 2.265 MB / 2.062 GB, free: 13.51 MB / 1.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006929/3.526, allocations: 3.935 MB / 2.066 GB, free: 8.863 MB / 1.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01181/3.538, allocations: 5.775 MB / 2.071 GB, free: 2.52 MB / 1.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.43e-05/3.538, allocations: 12 kB / 2.071 GB, free: 2.508 MB / 1.545 GB
Notification: Performance of matching and sorting (n=744) (initialization): time 0.02664/3.565, allocations: 8.293 MB / 2.079 GB, free: 10.18 MB / 1.561 GB
Notification: Performance of prepare postOptimizeDAE: time 8.173e-05/3.565, allocations: 24.03 kB / 2.079 GB, free: 10.15 MB / 1.561 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001109/3.565, allocations: 40 kB / 2.079 GB, free: 10.11 MB / 1.561 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009178/3.574, allocations: 1.691 MB / 2.081 GB, free: 8.41 MB / 1.561 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005545/3.58, allocations: 1.17 MB / 2.082 GB, free: 7.238 MB / 1.561 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.009731/3.59, allocations: 6.659 MB / 2.089 GB, free: 224 kB / 1.561 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009792/3.599, allocations: 364.2 kB / 2.089 GB, free: 15.86 MB / 1.576 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001494/3.601, allocations: 255.9 kB / 2.089 GB, free: 15.61 MB / 1.576 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01178/3.613, allocations: 4.173 MB / 2.093 GB, free: 11.43 MB / 1.576 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.008022/3.621, allocations: 3.861 MB / 2.097 GB, free: 6.852 MB / 1.576 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01097/3.632, allocations: 5.621 MB / 2.103 GB, free: 0.6523 MB / 1.576 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.742e-05/3.632, allocations: 12 kB / 2.103 GB, free: 0.6406 MB / 1.576 GB
Notification: Performance of matching and sorting (n=744) (initialization_lambda0): time 0.02555/3.658, allocations: 7.975 MB / 2.11 GB, free: 8.621 MB / 1.592 GB
Notification: Performance of prepare postOptimizeDAE: time 6.512e-05/3.658, allocations: 23.94 kB / 2.11 GB, free: 8.598 MB / 1.592 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.642e-05/3.658, allocations: 43.94 kB / 2.11 GB, free: 8.555 MB / 1.592 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008449/3.666, allocations: 1.502 MB / 2.112 GB, free: 7.039 MB / 1.592 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00529/3.672, allocations: 1.135 MB / 2.113 GB, free: 5.902 MB / 1.592 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01293/3.684, allocations: 8.87 MB / 2.122 GB, free: 12.57 MB / 1.607 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00996/3.694, allocations: 388.6 kB / 2.122 GB, free: 12.2 MB / 1.607 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001322/3.696, allocations: 235.9 kB / 2.122 GB, free: 11.96 MB / 1.607 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 180
 * Number of states: 0 ()
 * Number of discrete variables: 7 ($PRE.timTab.tab.nextTimeEvent,$whenCondition1,hysPum.y,$PRE.hysPum.y,timTab.tab.nextTimeEventScaled,$PRE.timTab.tab.nextTimeEventScaled,timTab.tab.nextTimeEvent)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (625):
 * Single equations (assignments): 616
 * Array equations: 2
 * Algorithm blocks: 0
 * Record equations: 2
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 1 system
   {1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,5,100.0%), (1,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(4,7), (13,22)}
Notification: Performance of prepare postOptimizeDAE: time 0.002427/3.698, allocations: 0.5018 MB / 2.123 GB, free: 11.46 MB / 1.607 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.004327/3.703, allocations: 0.9509 MB / 2.124 GB, free: 10.5 MB / 1.607 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01422/3.717, allocations: 5.054 MB / 2.129 GB, free: 5.379 MB / 1.607 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.008208/3.725, allocations: 3.748 MB / 2.132 GB, free: 1.57 MB / 1.607 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.288e-05/3.725, allocations: 0 / 2.132 GB, free: 1.57 MB / 1.607 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.007043/3.732, allocations: 1.537 MB / 2.134 GB, free: 8 kB / 1.607 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.003772/3.736, allocations: 75.98 kB / 2.134 GB, free: 15.93 MB / 1.623 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.007181/3.743, allocations: 226 kB / 2.134 GB, free: 15.71 MB / 1.623 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008511/3.744, allocations: 262.4 kB / 2.134 GB, free: 15.46 MB / 1.623 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01323/3.757, allocations: 4.76 MB / 2.139 GB, free: 10.63 MB / 1.623 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01461/3.772, allocations: 6.613 MB / 2.145 GB, free: 3.957 MB / 1.623 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003189/3.775, allocations: 1.741 MB / 2.147 GB, free: 2.207 MB / 1.623 GB
Notification: Performance of sorting global known variables: time 0.01464/3.79, allocations: 5.92 MB / 2.153 GB, free: 12.32 MB / 1.639 GB
Notification: Performance of Backend: time 1.993e-06/3.79, allocations: 0 / 2.153 GB, free: 12.32 MB / 1.639 GB
Notification: Performance of simCode: created initialization part: time 0.03806/3.828, allocations: 10.48 MB / 2.163 GB, free: 1.953 MB / 1.639 GB
Notification: Performance of SimCode: time 0.0591/3.887, allocations: 22.28 MB / 2.185 GB, free: 11.77 MB / 1.67 GB
Notification: Performance of Templates: time 1.856/5.743, allocations: 200.5 MB / 2.381 GB, free: 0.5245 GB / 1.67 GB
make -j1 -f Buildings_latest_Buildings.Examples.Tutorial.Boiler.System3.makefile
(rm -f Buildings_latest_Buildings.Examples.Tutorial.Boiler.System3.pipe ; mkfifo Buildings_latest_Buildings.Examples.Tutorial.Boiler.System3.pipe ; head -c 1048576 < Buildings_latest_Buildings.Examples.Tutorial.Boiler.System3.pipe >> ../files/Buildings_latest_Buildings.Examples.Tutorial.Boiler.System3.sim & ./Buildings_latest_Buildings.Examples.Tutorial.Boiler.System3  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Examples.Tutorial.Boiler.System3.pipe 2>&1)