Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Airflow.Multizone.Examples.Orifice.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 IBPSA with version 3.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/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)
Running command: translateModel(IBPSA.Airflow.Multizone.Examples.Orifice,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Airflow.Multizone.Examples.Orifice")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002705/0.00271, allocations: 358.4 kB / 16.59 MB, free: 5.562 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo): time 1.631/1.631, allocations: 165.8 MB / 183.1 MB, free: 5.43 MB / 138.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 4.078/4.078, allocations: 410.8 MB / 0.6001 GB, free: 15.21 MB / 298.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001748/0.001748, allocations: 198.7 kB / 0.6668 GB, free: 6.934 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0006317/0.0006321, allocations: 19.98 kB / 0.7429 GB, free: 8.973 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4491/0.4498, allocations: 67.43 MB / 0.8087 GB, free: 28.78 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Airflow.Multizone.Examples.Orifice): time 0.7113/1.161, allocations: 156.6 MB / 0.9616 GB, free: 1.387 MB / 474.7 MB
Notification: Performance of NFInst.instExpressions: time 0.005671/1.167, allocations: 2.402 MB / 0.964 GB, free: 1.348 MB / 474.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0008573/1.168, allocations: 14.62 kB / 0.964 GB, free: 1.348 MB / 474.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.00105/1.169, allocations: 207.6 kB / 0.9642 GB, free: 1.344 MB / 474.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.00353/1.172, allocations: 0.8001 MB / 0.965 GB, free: 1.336 MB / 474.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00264/1.175, allocations: 0.5522 MB / 0.9655 GB, free: 1.332 MB / 474.7 MB
Notification: Performance of NFFlatten.flatten: time 0.002368/1.177, allocations: 1.143 MB / 0.9666 GB, free: 1.332 MB / 474.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0007792/1.178, allocations: 259.5 kB / 0.9669 GB, free: 1.301 MB / 474.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001686/1.18, allocations: 473.4 kB / 0.9673 GB, free: 1.281 MB / 474.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006608/1.181, allocations: 307 kB / 0.9676 GB, free: 1.223 MB / 474.7 MB
Notification: Performance of NFPackage.collectConstants: time 7.498e-05/1.181, allocations: 22.28 kB / 0.9677 GB, free: 1.219 MB / 474.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001811/1.183, allocations: 450.3 kB / 0.9681 GB, free: 1.172 MB / 474.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0002283/1.183, allocations: 134.3 kB / 0.9682 GB, free: 1.156 MB / 474.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002615/1.183, allocations: 108.4 kB / 0.9683 GB, free: 1.109 MB / 474.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.001172/1.184, allocations: 0.531 MB / 0.9688 GB, free: 1.062 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.582e-06/1.184, allocations: 0 / 0.9688 GB, free: 1.062 MB / 474.7 MB
Notification: Performance of FrontEnd: time 2.865e-06/1.184, allocations: 0 / 0.9688 GB, free: 1.062 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 9.748e-06/1.184, allocations: 3.938 kB / 0.9688 GB, free: 1.059 MB / 474.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 74
 * Number of variables: 74
Notification: Performance of Generate backend data structure: time 0.001605/1.186, allocations: 0.7202 MB / 0.9695 GB, free: 0.5273 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.171e-05/1.186, allocations: 8.906 kB / 0.9695 GB, free: 0.5273 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001925/1.188, allocations: 186.6 kB / 0.9697 GB, free: 0.5 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0009256/1.189, allocations: 365.5 kB / 0.9701 GB, free: 256 kB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.327e-05/1.189, allocations: 23.61 kB / 0.9701 GB, free: 256 kB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 7.781e-05/1.189, allocations: 32.12 kB / 0.9701 GB, free: 256 kB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0009286/1.19, allocations: 377 kB / 0.9705 GB, free: 148 kB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.413e-05/1.19, allocations: 0 / 0.9705 GB, free: 148 kB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 4.71e-05/1.19, allocations: 10.84 kB / 0.9705 GB, free: 148 kB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.414e-05/1.19, allocations: 13.92 kB / 0.9705 GB, free: 148 kB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0008333/1.191, allocations: 271.7 kB / 0.9708 GB, free: 132 kB / 474.7 MB
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.002914/1.194, allocations: 1.674 MB / 0.9724 GB, free: 14.96 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0006026/1.195, allocations: 215 kB / 0.9726 GB, free: 14.93 MB / 490.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003732/1.195, allocations: 123.8 kB / 0.9727 GB, free: 14.92 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.001531/1.197, allocations: 0.8803 MB / 0.9736 GB, free: 13.98 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.225e-05/1.197, allocations: 40.25 kB / 0.9736 GB, free: 13.94 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=23): time 3.316e-06/1.197, allocations: 1.938 kB / 0.9736 GB, free: 13.94 MB / 490.7 MB
Notification: Performance of matching and sorting (n=23): time 0.001502/1.198, allocations: 365.8 kB / 0.974 GB, free: 13.88 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.65e-05/1.198, allocations: 62.05 kB / 0.974 GB, free: 13.81 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0006834/1.199, allocations: 419.1 kB / 0.9744 GB, free: 13.69 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 6.052e-05/1.199, allocations: 30.83 kB / 0.9745 GB, free: 13.66 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0001981/1.199, allocations: 221.8 kB / 0.9747 GB, free: 13.55 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001129/1.199, allocations: 80.28 kB / 0.9748 GB, free: 13.5 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 1.746e-05/1.199, allocations: 3.25 kB / 0.9748 GB, free: 13.5 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001594/1.2, allocations: 351.8 kB / 0.9751 GB, free: 13.19 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003329/1.2, allocations: 85.95 kB / 0.9752 GB, free: 13.19 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0003491/1.2, allocations: 125.4 kB / 0.9753 GB, free: 13.16 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0003466/1.201, allocations: 105.1 kB / 0.9754 GB, free: 13.15 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.32e-06/1.201, allocations: 0 / 0.9754 GB, free: 13.15 MB / 490.7 MB
Notification: Performance of matching and sorting (n=28) (initialization): time 0.0009247/1.202, allocations: 287.5 kB / 0.9757 GB, free: 13.09 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.299e-05/1.202, allocations: 7.438 kB / 0.9757 GB, free: 13.09 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.162e-05/1.202, allocations: 9.797 kB / 0.9757 GB, free: 13.08 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 2.03e-05/1.202, allocations: 3.312 kB / 0.9757 GB, free: 13.08 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004313/1.202, allocations: 50.5 kB / 0.9757 GB, free: 13.08 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.591e-05/1.202, allocations: 3.125 kB / 0.9757 GB, free: 13.08 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005156/1.203, allocations: 43 kB / 0.9758 GB, free: 13.06 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.181e-05/1.203, allocations: 18.08 kB / 0.9758 GB, free: 13.05 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 4
 * 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 (28):
 * Single equations (assignments): 28
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0003355/1.203, allocations: 158.5 kB / 0.9759 GB, free: 12.94 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001142/1.203, allocations: 40.44 kB / 0.976 GB, free: 12.92 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0009533/1.204, allocations: 308.1 kB / 0.9763 GB, free: 12.86 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.658e-06/1.204, allocations: 2.125 kB / 0.9763 GB, free: 12.86 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.567e-06/1.204, allocations: 0 / 0.9763 GB, free: 12.86 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002806/1.205, allocations: 142.8 kB / 0.9764 GB, free: 12.74 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 9.323e-05/1.205, allocations: 10.2 kB / 0.9764 GB, free: 12.74 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004878/1.205, allocations: 24.25 kB / 0.9765 GB, free: 12.74 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 7.673e-05/1.205, allocations: 16.27 kB / 0.9765 GB, free: 12.73 MB / 490.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.001111/1.206, allocations: 358.2 kB / 0.9768 GB, free: 12.61 MB / 490.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.001361/1.208, allocations: 0.5595 MB / 0.9774 GB, free: 12.38 MB / 490.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0003332/1.208, allocations: 101.5 kB / 0.9775 GB, free: 12.38 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.000502/1.209, allocations: 301.9 kB / 0.9777 GB, free: 12.29 MB / 490.7 MB
Notification: Performance of Backend: time 6.82e-07/1.209, allocations: 0 / 0.9777 GB, free: 12.29 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.00196/1.211, allocations: 412.6 kB / 0.9781 GB, free: 12.17 MB / 490.7 MB
Notification: Performance of SimCode: time 0.003682/1.214, allocations: 1.626 MB / 0.9797 GB, free: 11.1 MB / 490.7 MB
Notification: Performance of Templates: time 0.02663/1.241, allocations: 9.18 MB / 0.9887 GB, free: 6.609 MB / 490.7 MB