Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_9_Buildings.Fluid.Movers.Validation.ControlledFlowMachine.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.3-maint.9.1.x/package.mo", uses=false)
Using package Buildings with version 9.1.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.3-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.Movers.Validation.ControlledFlowMachine,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|fan1.m_flow|fan2.m_flow|fan3.m_flow|fan4.m_flow|fan1.dp|fan2.dp|fan3.dp|fan4.dp",fileNamePrefix="Buildings_9_Buildings.Fluid.Movers.Validation.ControlledFlowMachine")
translateModel(Buildings.Fluid.Movers.Validation.ControlledFlowMachine,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|fan1.m_flow|fan2.m_flow|fan3.m_flow|fan4.m_flow|fan1.dp|fan2.dp|fan3.dp|fan4.dp",fileNamePrefix="Buildings_9_Buildings.Fluid.Movers.Validation.ControlledFlowMachine")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001538/0.001538, allocations: 109.7 kB / 16.41 MB, free: 6.52 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.001774/0.001774, allocations: 186.7 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.314/1.314, allocations: 222.9 MB / 241.1 MB, free: 15.15 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.3-maint.9.1.x/package.mo): time 2.186/2.186, allocations: 390.3 MB / 0.6655 GB, free: 0.6445 MB / 0.5294 GB
Notification: Performance of FrontEnd - loaded program: time 0.001319/0.001319, allocations: 63.91 kB / 0.9516 GB, free: 7.637 MB / 0.7325 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.6558/0.6572, allocations: 140.4 MB / 1.089 GB, free: 5.773 MB / 0.8575 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/Blocks/Math.mo:543:5-544:48:writable] Warning: Parameter fan3.gaiSpe.k has no value, and is fixed during initialization (fixed=true), using available start value (start=1) as default value.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/Blocks/Math.mo:543:5-544:48:writable] Warning: Parameter fan2.gaiSpe.k has no value, and is fixed during initialization (fixed=true), using available start value (start=1) as default value.
Notification: Performance of NFInst.instantiate(Buildings.Fluid.Movers.Validation.ControlledFlowMachine): time 0.3731/1.03, allocations: 373.2 MB / 1.453 GB, free: 8.02 MB / 1.186 GB
Notification: Performance of NFInst.instExpressions: time 0.06241/1.093, allocations: 47.73 MB / 1.5 GB, free: 8.141 MB / 1.232 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.008123/1.101, allocations: 183 kB / 1.5 GB, free: 7.961 MB / 1.232 GB
Notification: Performance of NFTyping.typeComponents: time 0.008386/1.109, allocations: 3.112 MB / 1.503 GB, free: 4.832 MB / 1.232 GB
Notification: Performance of NFTyping.typeBindings: time 0.02136/1.131, allocations: 9.212 MB / 1.512 GB, free: 11.59 MB / 1.248 GB
Notification: Performance of NFTyping.typeClassSections: time 0.009869/1.141, allocations: 4.338 MB / 1.516 GB, free: 7.258 MB / 1.248 GB
Notification: Performance of NFFlatten.flatten: time 0.01655/1.157, allocations: 12.98 MB / 1.529 GB, free: 10.25 MB / 1.264 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.007592/1.165, allocations: 4.534 MB / 1.533 GB, free: 5.637 MB / 1.264 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01529/1.18, allocations: 7.595 MB / 1.541 GB, free: 14.02 MB / 1.279 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.006906/1.187, allocations: 4.112 MB / 1.545 GB, free: 9.902 MB / 1.279 GB
Notification: Performance of NFPackage.collectConstants: time 0.001718/1.189, allocations: 0.5469 MB / 1.545 GB, free: 9.355 MB / 1.279 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0131/1.202, allocations: 6.801 MB / 1.552 GB, free: 2.539 MB / 1.279 GB
Notification: Performance of NFScalarize.scalarize: time 0.003224/1.205, allocations: 2.583 MB / 1.554 GB, free: 15.95 MB / 1.295 GB
Notification: Performance of NFVerifyModel.verify: time 0.006166/1.211, allocations: 3.557 MB / 1.558 GB, free: 12.38 MB / 1.295 GB
Notification: Performance of NFConvertDAE.convert: time 0.0183/1.23, allocations: 11.79 MB / 1.569 GB, free: 0.5469 MB / 1.295 GB
Notification: Performance of FrontEnd - DAE generated: time 7.544e-06/1.23, allocations: 8 kB / 1.569 GB, free: 0.5391 MB / 1.295 GB
Notification: Performance of FrontEnd: time 1.542e-06/1.23, allocations: 0 / 1.569 GB, free: 0.5391 MB / 1.295 GB
Notification: Performance of Transformations before backend: time 0.000266/1.23, allocations: 0 / 1.569 GB, free: 0.5391 MB / 1.295 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 743
 * Number of variables: 743
Notification: Performance of Generate backend data structure: time 0.01782/1.248, allocations: 7.535 MB / 1.577 GB, free: 8.902 MB / 1.311 GB
Notification: Performance of prepare preOptimizeDAE: time 4.414e-05/1.248, allocations: 8.031 kB / 1.577 GB, free: 8.895 MB / 1.311 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01191/1.26, allocations: 1.787 MB / 1.578 GB, free: 7.098 MB / 1.311 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01325/1.273, allocations: 6.378 MB / 1.585 GB, free: 0.6289 MB / 1.311 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002386/1.273, allocations: 249.8 kB / 1.585 GB, free: 392 kB / 1.311 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001086/1.274, allocations: 336 kB / 1.585 GB, free: 56 kB / 1.311 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.4513/1.726, allocations: 4.214 MB / 1.589 GB, free: 443.4 MB / 1.311 GB
Notification: Performance of preOpt findStateOrder (simulation): time 6.499e-05/1.726, allocations: 6.844 kB / 1.589 GB, free: 443.4 MB / 1.311 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003126/1.726, allocations: 124.2 kB / 1.59 GB, free: 443.3 MB / 1.311 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001482/1.726, allocations: 110.8 kB / 1.59 GB, free: 443.3 MB / 1.311 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006633/1.733, allocations: 3.128 MB / 1.593 GB, free: 442.2 MB / 1.311 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.0228/1.756, allocations: 18.32 MB / 1.611 GB, free: 430.4 MB / 1.311 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004628/1.76, allocations: 2.417 MB / 1.613 GB, free: 429.5 MB / 1.311 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002234/1.763, allocations: 1.338 MB / 1.614 GB, free: 429 MB / 1.311 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002988/1.763, allocations: 227.1 kB / 1.614 GB, free: 428.8 MB / 1.311 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002833/1.766, allocations: 1.856 MB / 1.616 GB, free: 428.2 MB / 1.311 GB
Notification: Performance of pre-optimization done (n=168): time 4.228e-06/1.766, allocations: 3.969 kB / 1.616 GB, free: 428.2 MB / 1.311 GB
Notification: Performance of matching and sorting (n=168): time 0.009009/1.775, allocations: 4.151 MB / 1.62 GB, free: 426.6 MB / 1.311 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.615e-05/1.775, allocations: 74.38 kB / 1.62 GB, free: 426.6 MB / 1.311 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008475/1.783, allocations: 5.727 MB / 1.626 GB, free: 424.6 MB / 1.311 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005823/1.784, allocations: 81.7 kB / 1.626 GB, free: 424.5 MB / 1.311 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002119/1.786, allocations: 2.625 MB / 1.629 GB, free: 422.5 MB / 1.311 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0007813/1.787, allocations: 0.5825 MB / 1.629 GB, free: 422.1 MB / 1.311 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009223/1.788, allocations: 0.6244 MB / 1.63 GB, free: 421.8 MB / 1.311 GB
Notification: Performance of setup shared object (initialization): time 3.958e-05/1.788, allocations: 303.1 kB / 1.63 GB, free: 421.5 MB / 1.311 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00309/1.791, allocations: 2.103 MB / 1.632 GB, free: 420.8 MB / 1.311 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003798/1.795, allocations: 3.214 MB / 1.635 GB, free: 418.6 MB / 1.311 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.006847/1.802, allocations: 5.296 MB / 1.64 GB, free: 415.7 MB / 1.311 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.679e-05/1.802, allocations: 12.66 kB / 1.64 GB, free: 415.7 MB / 1.311 GB
Notification: Performance of matching and sorting (n=363) (initialization): time 0.009623/1.811, allocations: 6.071 MB / 1.646 GB, free: 413.4 MB / 1.311 GB
Notification: Performance of prepare postOptimizeDAE: time 5.54e-05/1.811, allocations: 44.97 kB / 1.646 GB, free: 413.4 MB / 1.311 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.969e-05/1.811, allocations: 32.5 kB / 1.646 GB, free: 413.4 MB / 1.311 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001225/1.813, allocations: 0.5875 MB / 1.647 GB, free: 413.2 MB / 1.311 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003255/1.816, allocations: 0.6549 MB / 1.648 GB, free: 413.1 MB / 1.311 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002759/1.819, allocations: 3.775 MB / 1.651 GB, free: 409.9 MB / 1.311 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004303/1.823, allocations: 374.5 kB / 1.652 GB, free: 409.7 MB / 1.311 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003795/1.823, allocations: 224.3 kB / 1.652 GB, free: 409.7 MB / 1.311 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.004774/1.828, allocations: 3.05 MB / 1.655 GB, free: 408.4 MB / 1.311 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003008/1.831, allocations: 2.88 MB / 1.658 GB, free: 406.4 MB / 1.311 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.005411/1.837, allocations: 4.646 MB / 1.662 GB, free: 403.7 MB / 1.311 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.097e-05/1.837, allocations: 9.156 kB / 1.662 GB, free: 403.7 MB / 1.311 GB
Notification: Performance of matching and sorting (n=363) (initialization_lambda0): time 0.008468/1.845, allocations: 5.474 MB / 1.668 GB, free: 401.6 MB / 1.311 GB
Notification: Performance of prepare postOptimizeDAE: time 3.606e-05/1.845, allocations: 45.36 kB / 1.668 GB, free: 401.5 MB / 1.311 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.002e-05/1.845, allocations: 35.22 kB / 1.668 GB, free: 401.5 MB / 1.311 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001505/1.847, allocations: 0.6778 MB / 1.668 GB, free: 401.4 MB / 1.311 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002398/1.849, allocations: 0.6518 MB / 1.669 GB, free: 401.3 MB / 1.311 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0101/1.859, allocations: 9.572 MB / 1.678 GB, free: 394.8 MB / 1.311 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004463/1.864, allocations: 298.1 kB / 1.679 GB, free: 394.7 MB / 1.311 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003809/1.864, allocations: 196.8 kB / 1.679 GB, free: 394.6 MB / 1.311 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 137
 * Number of states: 0 ()
 * Number of discrete variables: 5 (y.count,$PRE.y.count,y.T_start,$PRE.y.T_start,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (312):
 * Single equations (assignments): 302
 * Array equations: 4
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 3
 * Torn equation systems: 2
 * 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): 3 systems
   {1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(1,6), (1,4)}
Notification: Performance of prepare postOptimizeDAE: time 0.0008646/1.865, allocations: 363.3 kB / 1.679 GB, free: 394.3 MB / 1.311 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0009265/1.866, allocations: 0.5089 MB / 1.68 GB, free: 394 MB / 1.311 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006574/1.873, allocations: 3.192 MB / 1.683 GB, free: 392.4 MB / 1.311 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.004012/1.877, allocations: 2.809 MB / 1.686 GB, free: 390.8 MB / 1.311 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.923e-06/1.877, allocations: 4 kB / 1.686 GB, free: 390.8 MB / 1.311 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002088/1.879, allocations: 0.8183 MB / 1.686 GB, free: 390.2 MB / 1.311 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0007241/1.88, allocations: 36.72 kB / 1.686 GB, free: 390.2 MB / 1.311 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002364/1.882, allocations: 114 kB / 1.686 GB, free: 390.1 MB / 1.311 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002498/1.882, allocations: 135.8 kB / 1.687 GB, free: 390 MB / 1.311 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005723/1.888, allocations: 2.993 MB / 1.69 GB, free: 387.6 MB / 1.311 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.006007/1.894, allocations: 4.377 MB / 1.694 GB, free: 383.2 MB / 1.311 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001541/1.895, allocations: 1.152 MB / 1.695 GB, free: 382 MB / 1.311 GB
Notification: Performance of sorting global known variables: time 0.005005/1.9, allocations: 3.945 MB / 1.699 GB, free: 378.1 MB / 1.311 GB
Notification: Performance of Backend: time 1.052e-06/1.901, allocations: 0 / 1.699 GB, free: 378.1 MB / 1.311 GB
Notification: Performance of simCode: created initialization part: time 0.0145/1.915, allocations: 7.323 MB / 1.706 GB, free: 371 MB / 1.311 GB
Notification: Performance of SimCode: time 0.02022/1.935, allocations: 13.03 MB / 1.719 GB, free: 358 MB / 1.311 GB
Notification: Performance of Templates: time 0.1721/2.107, allocations: 141 MB / 1.856 GB, free: 218.8 MB / 1.311 GB
make -j1 -f Buildings_9_Buildings.Fluid.Movers.Validation.ControlledFlowMachine.makefile
(rm -f Buildings_9_Buildings.Fluid.Movers.Validation.ControlledFlowMachine.pipe ; mkfifo Buildings_9_Buildings.Fluid.Movers.Validation.ControlledFlowMachine.pipe ; head -c 1048576 < Buildings_9_Buildings.Fluid.Movers.Validation.ControlledFlowMachine.pipe >> ../files/Buildings_9_Buildings.Fluid.Movers.Validation.ControlledFlowMachine.sim & ./Buildings_9_Buildings.Fluid.Movers.Validation.ControlledFlowMachine  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_9_Buildings.Fluid.Movers.Validation.ControlledFlowMachine.pipe 2>&1)