Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_9_Buildings.Examples.VAVCO2.VAVSystemCTControl.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.Examples.VAVCO2.VAVSystemCTControl,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|roo.roo45.vavACH.y|roo.roo46.vavACH.y|roo.roo47.vavACH.y|roo.roo48.vavACH.y|roo.roo49.vavACH.y|roo.roo50.vavACH.y|fan32.m_flow|fan56.m_flow|fan32.dp|fan56.dp|roo.roo45.volFraCO2.V|roo.roo46.volFraCO2.V|roo.roo47.volFraCO2.V|roo.roo48.volFraCO2.V|roo.roo49.volFraCO2.V|roo.roo50.volFraCO2.V|roo.roo45.con.CO2Set|roo.roo45.vol.C.1.|roo.roo46.vol.C.1.|roo.roo47.vol.C.1.|roo.roo48.vol.C.1.|roo.roo49.vol.C.1.|roo.roo50.vol.C.1.|roo.roo45.ple.C.1.|roo.roo46.ple.C.1.|roo.roo47.ple.C.1.|roo.roo48.ple.C.1.|roo.roo49.ple.C.1.|roo.roo50.ple.C.1.",fileNamePrefix="Buildings_9_Buildings.Examples.VAVCO2.VAVSystemCTControl") translateModel(Buildings.Examples.VAVCO2.VAVSystemCTControl,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|roo.roo45.vavACH.y|roo.roo46.vavACH.y|roo.roo47.vavACH.y|roo.roo48.vavACH.y|roo.roo49.vavACH.y|roo.roo50.vavACH.y|fan32.m_flow|fan56.m_flow|fan32.dp|fan56.dp|roo.roo45.volFraCO2.V|roo.roo46.volFraCO2.V|roo.roo47.volFraCO2.V|roo.roo48.volFraCO2.V|roo.roo49.volFraCO2.V|roo.roo50.volFraCO2.V|roo.roo45.con.CO2Set|roo.roo45.vol.C.1.|roo.roo46.vol.C.1.|roo.roo47.vol.C.1.|roo.roo48.vol.C.1.|roo.roo49.vol.C.1.|roo.roo50.vol.C.1.|roo.roo45.ple.C.1.|roo.roo46.ple.C.1.|roo.roo47.ple.C.1.|roo.roo48.ple.C.1.|roo.roo49.ple.C.1.|roo.roo50.ple.C.1.",fileNamePrefix="Buildings_9_Buildings.Examples.VAVCO2.VAVSystemCTControl") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001701/0.001701, allocations: 115.3 kB / 16.42 MB, free: 6.02 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.001705/0.001705, allocations: 186.1 kB / 17.35 MB, free: 5.633 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.652/1.652, allocations: 222.9 MB / 241 MB, free: 15.17 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.264/2.264, allocations: 390.2 MB / 0.6655 GB, free: 0.6484 MB / 0.5294 GB Notification: Performance of FrontEnd - loaded program: time 0.00146/0.00146, allocations: 60.09 kB / 0.9515 GB, free: 7.68 MB / 0.7325 GB Notification: Performance of FrontEnd - Absyn->SCode: time 1.108/1.11, allocations: 140.4 MB / 1.089 GB, free: 5.543 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 fan32.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 fan56.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.Examples.VAVCO2.VAVSystemCTControl): time 3.983/5.093, allocations: 1.242 GB / 2.331 GB, free: 12.55 MB / 1.545 GB Notification: Performance of NFInst.instExpressions: time 0.2097/5.303, allocations: 121.8 MB / 2.45 GB, free: 8.062 MB / 1.545 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.05969/5.363, allocations: 0.7551 MB / 2.45 GB, free: 8.062 MB / 1.545 GB Notification: Performance of NFTyping.typeComponents: time 0.04959/5.413, allocations: 10.7 MB / 2.461 GB, free: 4.562 MB / 1.545 GB Notification: Performance of NFTyping.typeBindings: time 0.09939/5.512, allocations: 27.11 MB / 2.487 GB, free: 7.754 MB / 1.561 GB Notification: Performance of NFTyping.typeClassSections: time 0.05473/5.567, allocations: 16.17 MB / 2.503 GB, free: 13.66 MB / 1.576 GB Notification: Performance of NFFlatten.flatten: time 0.0871/5.654, allocations: 46.94 MB / 2.549 GB, free: 10.56 MB / 1.607 GB Notification: Performance of NFFlatten.resolveConnections: time 0.05863/5.713, allocations: 22.45 MB / 2.571 GB, free: 4.418 MB / 1.623 GB Notification: Performance of NFEvalConstants.evaluate: time 0.07686/5.789, allocations: 25.49 MB / 2.596 GB, free: 12.65 MB / 1.654 GB Notification: Performance of NFSimplifyModel.simplify: time 0.03608/5.826, allocations: 16.25 MB / 2.612 GB, free: 13.57 MB / 1.67 GB Notification: Performance of NFPackage.collectConstants: time 0.01538/5.841, allocations: 2.957 MB / 2.614 GB, free: 10.61 MB / 1.67 GB Notification: Performance of NFFlatten.collectFunctions: time 0.06096/5.902, allocations: 17.6 MB / 2.632 GB, free: 11.01 MB / 1.686 GB Notification: Performance of NFScalarize.scalarize: time 0.0235/5.926, allocations: 8.867 MB / 2.64 GB, free: 2.273 MB / 1.686 GB Notification: Performance of NFVerifyModel.verify: time 0.04435/5.97, allocations: 12.79 MB / 2.653 GB, free: 5.586 MB / 1.701 GB Notification: Performance of NFConvertDAE.convert: time 0.1004/6.07, allocations: 42.46 MB / 2.694 GB, free: 12.4 MB / 1.748 GB Notification: Performance of FrontEnd - DAE generated: time 1.158e-05/6.07, allocations: 4 kB / 2.694 GB, free: 12.39 MB / 1.748 GB Notification: Performance of FrontEnd: time 2.665e-06/6.071, allocations: 4 kB / 2.694 GB, free: 12.39 MB / 1.748 GB Notification: Performance of Transformations before backend: time 0.001654/6.072, allocations: 0 / 2.694 GB, free: 12.39 MB / 1.748 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 3086 * Number of variables: 3086 Notification: Performance of Generate backend data structure: time 0.094/6.166, allocations: 22.57 MB / 2.716 GB, free: 5.809 MB / 1.764 GB Notification: Performance of prepare preOptimizeDAE: time 7.766e-05/6.166, allocations: 8.031 kB / 2.716 GB, free: 5.801 MB / 1.764 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.03319/6.2, allocations: 4.18 MB / 2.72 GB, free: 1.613 MB / 1.764 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.05357/6.253, allocations: 17.01 MB / 2.737 GB, free: 0.7852 MB / 1.779 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001436/6.255, allocations: 0.943 MB / 2.738 GB, free: 15.84 MB / 1.795 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.007138/6.262, allocations: 1.279 MB / 2.739 GB, free: 14.57 MB / 1.795 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.06263/6.324, allocations: 17.29 MB / 2.756 GB, free: 13.05 MB / 1.811 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.0007058/6.325, allocations: 11.92 kB / 2.756 GB, free: 13.04 MB / 1.811 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.006037/6.331, allocations: 498.2 kB / 2.756 GB, free: 12.56 MB / 1.811 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.000588/6.332, allocations: 483.6 kB / 2.757 GB, free: 12.09 MB / 1.811 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04677/6.379, allocations: 16.04 MB / 2.773 GB, free: 12.04 MB / 1.826 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.1761/6.555, allocations: 83.66 MB / 2.854 GB, free: 3.148 MB / 1.904 GB Notification: Performance of preOpt comSubExp (simulation): time 0.02548/6.58, allocations: 10.33 MB / 2.864 GB, free: 8.852 MB / 1.92 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.01674/6.597, allocations: 6.881 MB / 2.871 GB, free: 1.961 MB / 1.92 GB Notification: Performance of preOpt evalFunc (simulation): time 0.001345/6.599, allocations: 324.4 kB / 2.871 GB, free: 1.68 MB / 1.92 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 1.598/8.196, allocations: 7.008 MB / 2.878 GB, free: 0.9771 GB / 1.92 GB Notification: Performance of preOpt simplifyInStream (simulation): time 0.01994/8.217, allocations: 1.836 MB / 2.88 GB, free: 0.9771 GB / 1.92 GB Notification: Performance of pre-optimization done (n=876): time 0.0001541/8.217, allocations: 0 / 2.88 GB, free: 0.9771 GB / 1.92 GB Notification: Performance of matching and sorting (n=876): time 0.1544/8.371, allocations: 30.97 MB / 2.91 GB, free: 0.9687 GB / 1.92 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.000328/8.372, allocations: 166.6 kB / 2.91 GB, free: 0.9686 GB / 1.92 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.05908/8.431, allocations: 13.7 MB / 2.924 GB, free: 0.9639 GB / 1.92 GB Notification: Performance of collectPreVariables (initialization): time 0.004313/8.435, allocations: 229.7 kB / 2.924 GB, free: 0.9637 GB / 1.92 GB Notification: Performance of collectInitialEqns (initialization): time 0.01536/8.45, allocations: 7.743 MB / 2.932 GB, free: 0.9591 GB / 1.92 GB Notification: Performance of collectInitialBindings (initialization): time 0.008131/8.459, allocations: 3.03 MB / 2.935 GB, free: 0.9568 GB / 1.92 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.01435/8.473, allocations: 1.941 MB / 2.936 GB, free: 0.956 GB / 1.92 GB Notification: Performance of setup shared object (initialization): time 9.378e-05/8.473, allocations: 302.2 kB / 2.937 GB, free: 0.9557 GB / 1.92 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0161/8.489, allocations: 5.386 MB / 2.942 GB, free: 0.9535 GB / 1.92 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.02109/8.51, allocations: 8.124 MB / 2.95 GB, free: 0.9482 GB / 1.92 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.03156/8.542, allocations: 13.07 MB / 2.963 GB, free: 0.9413 GB / 1.92 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001674/8.542, allocations: 11.84 kB / 2.963 GB, free: 0.9413 GB / 1.92 GB Notification: Performance of matching and sorting (n=1134) (initialization): time 0.08108/8.623, allocations: 19.37 MB / 2.982 GB, free: 0.933 GB / 1.92 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001149/8.624, allocations: 19.84 kB / 2.982 GB, free: 0.933 GB / 1.92 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001172/8.624, allocations: 39.47 kB / 2.982 GB, free: 0.933 GB / 1.92 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.02525/8.649, allocations: 5.436 MB / 2.987 GB, free: 0.9306 GB / 1.92 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01187/8.661, allocations: 1.926 MB / 2.989 GB, free: 0.9301 GB / 1.92 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04865/8.71, allocations: 33.77 MB / 3.022 GB, free: 0.9045 GB / 1.92 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0203/8.73, allocations: 0.6434 MB / 3.023 GB, free: 0.9041 GB / 1.92 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003686/8.734, allocations: 475.4 kB / 3.023 GB, free: 0.904 GB / 1.92 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.02463/8.758, allocations: 9.064 MB / 3.032 GB, free: 0.8989 GB / 1.92 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01507/8.773, allocations: 7.966 MB / 3.04 GB, free: 0.891 GB / 1.92 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.02497/8.798, allocations: 12.71 MB / 3.052 GB, free: 0.878 GB / 1.92 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 7.979e-05/8.799, allocations: 12 kB / 3.052 GB, free: 0.878 GB / 1.92 GB Notification: Performance of matching and sorting (n=1134) (initialization_lambda0): time 0.06255/8.861, allocations: 18.56 MB / 3.07 GB, free: 0.8598 GB / 1.92 GB Notification: Performance of prepare postOptimizeDAE: time 7.952e-05/8.861, allocations: 26.17 kB / 3.07 GB, free: 0.8598 GB / 1.92 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.043e-05/8.861, allocations: 36 kB / 3.07 GB, free: 0.8598 GB / 1.92 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.02173/8.883, allocations: 4.826 MB / 3.075 GB, free: 0.855 GB / 1.92 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009826/8.893, allocations: 1.821 MB / 3.077 GB, free: 0.8533 GB / 1.92 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05279/8.946, allocations: 38.31 MB / 3.114 GB, free: 0.8146 GB / 1.92 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02077/8.967, allocations: 0.7558 MB / 3.115 GB, free: 0.8139 GB / 1.92 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003392/8.97, allocations: 467.6 kB / 3.115 GB, free: 0.8134 GB / 1.92 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 168 * Number of states: 0 () * Number of discrete variables: 34 ($PRE.roo.occ.office1.nextTimeEvent,$whenCondition7,$PRE.roo.occ.office2.nextTimeEvent,$whenCondition6,$PRE.roo.occ.cla1.nextTimeEvent,$whenCondition5,$PRE.roo.occ.cla2.nextTimeEvent,$whenCondition4,$PRE.roo.occ.smaRoo1.nextTimeEvent,$whenCondition3,$PRE.roo.occ.smaRoo2.nextTimeEvent,$whenCondition2,$whenCondition1,onSig.pulseStart,$PRE.onSig.pulseStart,onSig.y,roo.occ.smaRoo2.nextTimeEventScaled,$PRE.roo.occ.smaRoo2.nextTimeEventScaled,roo.occ.smaRoo2.nextTimeEvent,roo.occ.smaRoo1.nextTimeEventScaled,$PRE.roo.occ.smaRoo1.nextTimeEventScaled,roo.occ.smaRoo1.nextTimeEvent,roo.occ.cla2.nextTimeEventScaled,$PRE.roo.occ.cla2.nextTimeEventScaled,roo.occ.cla2.nextTimeEvent,roo.occ.cla1.nextTimeEventScaled,$PRE.roo.occ.cla1.nextTimeEventScaled,roo.occ.cla1.nextTimeEvent,roo.occ.office2.nextTimeEventScaled,$PRE.roo.occ.office2.nextTimeEventScaled,roo.occ.office2.nextTimeEvent,roo.occ.office1.nextTimeEventScaled,$PRE.roo.occ.office1.nextTimeEventScaled,roo.occ.office1.nextTimeEvent) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (956): * Single equations (assignments): 937 * Array equations: 2 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 8 * Torn equation systems: 9 * 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): 8 systems {1, 1, 1, 1, 1, 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): 6 systems {(4,9,87.5%), (5,8,76.0%), (4,9,81.2%), (4,9,81.2%), (4,9,81.2%), (4,9,81.2%)} * Non-linear torn systems (#iteration vars, #inner vars): 3 systems {(2,10), (15,43), (9,26)} Notification: Performance of prepare postOptimizeDAE: time 0.006707/8.977, allocations: 0.7396 MB / 3.116 GB, free: 0.8127 GB / 1.92 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.007589/8.984, allocations: 1.439 MB / 3.117 GB, free: 0.8113 GB / 1.92 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04865/9.033, allocations: 16.04 MB / 3.133 GB, free: 0.7956 GB / 1.92 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.035/9.068, allocations: 13.25 MB / 3.146 GB, free: 0.7826 GB / 1.92 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.705e-05/9.068, allocations: 0 / 3.146 GB, free: 0.7826 GB / 1.92 GB Notification: Performance of postOpt removeConstants (simulation): time 0.01188/9.08, allocations: 3.095 MB / 3.149 GB, free: 0.7796 GB / 1.92 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.007669/9.088, allocations: 211.9 kB / 3.149 GB, free: 0.7794 GB / 1.92 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01632/9.104, allocations: 437.6 kB / 3.15 GB, free: 0.7789 GB / 1.92 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003395/9.108, allocations: 0.6779 MB / 3.15 GB, free: 0.7783 GB / 1.92 GB Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.04297/9.151, allocations: 15.51 MB / 3.165 GB, free: 0.7633 GB / 1.92 GB Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.04432/9.195, allocations: 19.94 MB / 3.185 GB, free: 0.7437 GB / 1.92 GB Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0107/9.206, allocations: 5.887 MB / 3.191 GB, free: 0.738 GB / 1.92 GB Notification: Performance of sorting global known variables: time 0.02292/9.229, allocations: 9.4 MB / 3.2 GB, free: 0.7289 GB / 1.92 GB Notification: Performance of Backend: time 6.81e-07/9.229, allocations: 0 / 3.2 GB, free: 0.7289 GB / 1.92 GB Notification: Performance of simCode: created initialization part: time 0.07266/9.301, allocations: 23.21 MB / 3.223 GB, free: 0.7068 GB / 1.92 GB Notification: Performance of SimCode: time 0.09445/9.396, allocations: 38.03 MB / 3.26 GB, free: 0.6699 GB / 1.92 GB Notification: Performance of Templates: time 0.5351/9.931, allocations: 383.6 MB / 3.634 GB, free: 304.6 MB / 1.92 GB make -j1 -f Buildings_9_Buildings.Examples.VAVCO2.VAVSystemCTControl.makefile (rm -f Buildings_9_Buildings.Examples.VAVCO2.VAVSystemCTControl.pipe ; mkfifo Buildings_9_Buildings.Examples.VAVCO2.VAVSystemCTControl.pipe ; head -c 1048576 < Buildings_9_Buildings.Examples.VAVCO2.VAVSystemCTControl.pipe >> ../files/Buildings_9_Buildings.Examples.VAVCO2.VAVSystemCTControl.sim & ./Buildings_9_Buildings.Examples.VAVCO2.VAVSystemCTControl -abortSlowSimulation -alarm=480 -emit_protected -lv LOG_STATS > Buildings_9_Buildings.Examples.VAVCO2.VAVSystemCTControl.pipe 2>&1)