Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_master_Buildings.Examples.VAVCO2.VAVSystemCTControl.conf.json
Using package ModelicaServices with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Using package Buildings with version 9.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.0.0-master/package.mo)
Running command: translateModel(Buildings.Examples.VAVCO2.VAVSystemCTControl,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_master_Buildings.Examples.VAVCO2.VAVSystemCTControl")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.0.0-master/package.mo): time 5.037/5.037, allocations: 0.5623 GB / 0.5776 GB, free: 3.316 MB / 474.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002536/0.002536, allocations: 360.8 kB / 0.6447 GB, free: 185.6 MB / 474.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 4.416/4.416, allocations: 410.7 MB / 1.113 GB, free: 6.008 MB / 0.6042 GB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001904/0.001904, allocations: 179 kB / 1.226 GB, free: 6.188 MB / 0.7136 GB
Notification: Performance of FrontEnd - loaded program: time 0.0006834/0.0006837, allocations: 12 kB / 1.349 GB, free: 8.332 MB / 0.8386 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.789/0.7897, allocations: 115.3 MB / 1.461 GB, free: 284.4 MB / 0.8542 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.VAVCO2.VAVSystemCTControl): time 3.362/4.152, allocations: 1.086 GB / 2.547 GB, free: 8.609 MB / 1.182 GB
Notification: Performance of NFInst.instExpressions: time 1.397/5.549, allocations: 128.7 MB / 2.673 GB, free: 55.38 MB / 1.214 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.03584/5.585, allocations: 0.6319 MB / 2.674 GB, free: 55.38 MB / 1.214 GB
Notification: Performance of NFTyping.typeComponents: time 0.05657/5.641, allocations: 9.364 MB / 2.683 GB, free: 55.36 MB / 1.214 GB
Notification: Performance of NFTyping.typeBindings: time 0.1652/5.806, allocations: 33.82 MB / 2.716 GB, free: 54.98 MB / 1.214 GB
Notification: Performance of NFTyping.typeClassSections: time 0.07382/5.88, allocations: 17.3 MB / 2.733 GB, free: 52.85 MB / 1.214 GB
Notification: Performance of NFFlatten.flatten: time 0.08904/5.969, allocations: 51.46 MB / 2.783 GB, free: 37.86 MB / 1.214 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0563/6.026, allocations: 15.54 MB / 2.798 GB, free: 32.09 MB / 1.214 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.056/6.082, allocations: 16.55 MB / 2.814 GB, free: 23.39 MB / 1.214 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.03471/6.117, allocations: 14.4 MB / 2.828 GB, free: 11.91 MB / 1.214 GB
Notification: Performance of NFPackage.collectConstants: time 0.008757/6.125, allocations: 0.9297 MB / 2.829 GB, free: 10.98 MB / 1.214 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.03692/6.162, allocations: 6.848 MB / 2.836 GB, free: 5.52 MB / 1.214 GB
Notification: Performance of NFScalarize.scalarize: time 0.01639/6.179, allocations: 6.697 MB / 2.843 GB, free: 316 kB / 1.214 GB
Notification: Performance of NFVerifyModel.verify: time 0.01441/6.193, allocations: 4.295 MB / 2.847 GB, free: 13.79 MB / 1.229 GB
Notification: Performance of NFConvertDAE.convert: time 0.06828/6.262, allocations: 31.99 MB / 2.878 GB, free: 2.922 MB / 1.245 GB
Notification: Performance of FrontEnd - DAE generated: time 9.147e-06/6.262, allocations: 0 / 2.878 GB, free: 2.922 MB / 1.245 GB
Notification: Performance of FrontEnd: time 3.427e-06/6.262, allocations: 1.641 kB / 2.878 GB, free: 2.922 MB / 1.245 GB
Notification: Performance of Transformations before backend: time 0.001158/6.263, allocations: 4 kB / 2.878 GB, free: 2.918 MB / 1.245 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3092
 * Number of variables: 3092
Notification: Performance of Generate backend data structure: time 0.08214/6.345, allocations: 18.28 MB / 2.896 GB, free: 2.207 MB / 1.26 GB
Notification: Performance of prepare preOptimizeDAE: time 5.631e-05/6.345, allocations: 11.62 kB / 2.896 GB, free: 2.199 MB / 1.26 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.03718/6.382, allocations: 4.051 MB / 2.9 GB, free: 14.34 MB / 1.276 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.06076/6.443, allocations: 15.92 MB / 2.915 GB, free: 4.426 MB / 1.276 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002307/6.445, allocations: 0.9816 MB / 2.916 GB, free: 3.844 MB / 1.276 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.008168/6.454, allocations: 1.304 MB / 2.918 GB, free: 2.953 MB / 1.276 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.07895/6.533, allocations: 17.3 MB / 2.935 GB, free: 7.605 MB / 1.292 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0007323/6.533, allocations: 9.484 kB / 2.935 GB, free: 7.598 MB / 1.292 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.005396/6.539, allocations: 0.4922 MB / 2.935 GB, free: 7.488 MB / 1.292 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0008216/6.54, allocations: 462.6 kB / 2.935 GB, free: 7.047 MB / 1.292 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.05927/6.599, allocations: 15.87 MB / 2.951 GB, free: 13.39 MB / 1.307 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.2143/6.813, allocations: 83.37 MB / 3.032 GB, free: 13.3 MB / 1.385 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.03592/6.849, allocations: 10.21 MB / 3.042 GB, free: 3.148 MB / 1.385 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.02119/6.871, allocations: 6.889 MB / 3.049 GB, free: 12.26 MB / 1.401 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001543/6.872, allocations: 332.4 kB / 3.049 GB, free: 11.97 MB / 1.401 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0242/6.896, allocations: 6.993 MB / 3.056 GB, free: 4.938 MB / 1.401 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.02917/6.926, allocations: 3.456 MB / 3.06 GB, free: 1.523 MB / 1.401 GB
Notification: Performance of pre-optimization done (n=888): time 3.911e-05/6.926, allocations: 0 / 3.06 GB, free: 1.523 MB / 1.401 GB
Notification: Performance of matching and sorting (n=888): time 0.1055/7.031, allocations: 21.62 MB / 3.081 GB, free: 11.84 MB / 1.432 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.000392/7.032, allocations: 0.6315 MB / 3.081 GB, free: 11.08 MB / 1.432 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.04277/7.075, allocations: 13.6 MB / 3.095 GB, free: 13.58 MB / 1.448 GB
Notification: Performance of collectPreVariables (initialization): time 0.00326/7.078, allocations: 241.7 kB / 3.095 GB, free: 13.34 MB / 1.448 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0143/7.092, allocations: 8.005 MB / 3.103 GB, free: 5.406 MB / 1.448 GB
Notification: Performance of collectInitialBindings (initialization): time 0.007053/7.099, allocations: 3.061 MB / 3.106 GB, free: 2.371 MB / 1.448 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002687/7.102, allocations: 182.8 kB / 3.106 GB, free: 2.195 MB / 1.448 GB
Notification: Performance of setup shared object (initialization): time 0.001155/7.103, allocations: 0.6628 MB / 3.106 GB, free: 1.523 MB / 1.448 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 1.463/8.566, allocations: 5.091 MB / 3.111 GB, free: 0.7786 GB / 1.448 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.02888/8.595, allocations: 8.132 MB / 3.119 GB, free: 0.775 GB / 1.448 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02831/8.623, allocations: 6.335 MB / 3.126 GB, free: 0.772 GB / 1.448 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.109e-05/8.624, allocations: 11.88 kB / 3.126 GB, free: 0.772 GB / 1.448 GB
Notification: Performance of matching and sorting (n=1170) (initialization): time 0.06687/8.691, allocations: 15.5 MB / 3.141 GB, free: 0.7664 GB / 1.448 GB
Notification: Performance of prepare postOptimizeDAE: time 0.001019/8.692, allocations: 1.286 MB / 3.142 GB, free: 0.7648 GB / 1.448 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001825/8.692, allocations: 48.88 kB / 3.142 GB, free: 0.7648 GB / 1.448 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009994/8.702, allocations: 1.817 MB / 3.144 GB, free: 0.7645 GB / 1.448 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02224/8.724, allocations: 2.136 MB / 3.146 GB, free: 0.7644 GB / 1.448 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01216/8.736, allocations: 8.292 MB / 3.154 GB, free: 0.7574 GB / 1.448 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.03126/8.768, allocations: 1.444 MB / 3.155 GB, free: 0.7572 GB / 1.448 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003303/8.771, allocations: 483.4 kB / 3.156 GB, free: 0.7572 GB / 1.448 GB
Notification: Performance of prepare postOptimizeDAE: time 1.807e-05/8.771, allocations: 3.969 kB / 3.156 GB, free: 0.7572 GB / 1.448 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.04889/8.82, allocations: 13.69 MB / 3.169 GB, free: 0.7515 GB / 1.448 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002215/8.82, allocations: 45.55 kB / 3.169 GB, free: 0.7515 GB / 1.448 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00533/8.826, allocations: 1.287 MB / 3.171 GB, free: 0.7513 GB / 1.448 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0198/8.845, allocations: 2.035 MB / 3.173 GB, free: 0.7511 GB / 1.448 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02383/8.869, allocations: 12.26 MB / 3.184 GB, free: 0.7433 GB / 1.448 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02963/8.899, allocations: 1.424 MB / 3.186 GB, free: 0.7431 GB / 1.448 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002942/8.902, allocations: 465.7 kB / 3.186 GB, free: 0.7431 GB / 1.448 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 ($whenCondition7,$PRE.roo.occ.office1.nextTimeEvent,$whenCondition6,$PRE.roo.occ.office2.nextTimeEvent,$whenCondition5,$PRE.roo.occ.cla1.nextTimeEvent,$whenCondition4,$PRE.roo.occ.cla2.nextTimeEvent,$whenCondition3,$PRE.roo.occ.smaRoo1.nextTimeEvent,$whenCondition2,$PRE.roo.occ.smaRoo2.nextTimeEvent,$whenCondition1,roo.occ.office1.nextTimeEvent,$PRE.roo.occ.office1.nextTimeEventScaled,roo.occ.office1.nextTimeEventScaled,roo.occ.office2.nextTimeEvent,$PRE.roo.occ.office2.nextTimeEventScaled,roo.occ.office2.nextTimeEventScaled,roo.occ.cla1.nextTimeEvent,$PRE.roo.occ.cla1.nextTimeEventScaled,roo.occ.cla1.nextTimeEventScaled,roo.occ.cla2.nextTimeEvent,$PRE.roo.occ.cla2.nextTimeEventScaled,roo.occ.cla2.nextTimeEventScaled,roo.occ.smaRoo1.nextTimeEvent,$PRE.roo.occ.smaRoo1.nextTimeEventScaled,roo.occ.smaRoo1.nextTimeEventScaled,roo.occ.smaRoo2.nextTimeEvent,$PRE.roo.occ.smaRoo2.nextTimeEventScaled,roo.occ.smaRoo2.nextTimeEventScaled,onSig.y,$PRE.onSig.pulseStart,onSig.pulseStart)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1052):
 * Single equations (assignments): 1027
 * Array equations: 8
 * Algorithm blocks: 0
 * Record equations: 6
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 8
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 0
 * Non-linear Jacobian: 8 {1,1,1,1,1,1,1,1}
 * Without analytic Jacobian: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 3 {3 9,15 43,9 26}
Notification: Performance of prepare postOptimizeDAE: time 0.01503/8.917, allocations: 4.418 MB / 3.191 GB, free: 0.7407 GB / 1.448 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.005733/8.923, allocations: 1.447 MB / 3.192 GB, free: 0.7403 GB / 1.448 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.05098/8.974, allocations: 14.07 MB / 3.206 GB, free: 0.7315 GB / 1.448 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.03644/9.01, allocations: 11.33 MB / 3.217 GB, free: 0.7204 GB / 1.448 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.934e-05/9.01, allocations: 4 kB / 3.217 GB, free: 0.7204 GB / 1.448 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01369/9.024, allocations: 3.107 MB / 3.22 GB, free: 0.7173 GB / 1.448 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.006621/9.031, allocations: 212 kB / 3.22 GB, free: 0.7171 GB / 1.448 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02391/9.055, allocations: 1.158 MB / 3.221 GB, free: 0.7161 GB / 1.448 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003028/9.058, allocations: 0.6178 MB / 3.222 GB, free: 0.7155 GB / 1.448 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.05378/9.112, allocations: 13.6 MB / 3.235 GB, free: 0.7025 GB / 1.448 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.05852/9.17, allocations: 20.44 MB / 3.255 GB, free: 0.6824 GB / 1.448 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01459/9.185, allocations: 5.714 MB / 3.261 GB, free: 0.6769 GB / 1.448 GB
Notification: Performance of sorting global known variables: time 0.02584/9.211, allocations: 9.29 MB / 3.27 GB, free: 0.6679 GB / 1.448 GB
Notification: Performance of Backend: time 7.21e-07/9.211, allocations: 0 / 3.27 GB, free: 0.6679 GB / 1.448 GB
Notification: Performance of simCode: created initialization part: time 0.07528/9.286, allocations: 19.43 MB / 3.289 GB, free: 0.6499 GB / 1.448 GB
Notification: Performance of SimCode: time 0.1218/9.408, allocations: 43.05 MB / 3.331 GB, free: 0.6089 GB / 1.448 GB
Notification: Performance of Templates: time 0.8421/10.25, allocations: 340.5 MB / 3.663 GB, free: 285.1 MB / 1.448 GB