Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortAir.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.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortAir,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortAir")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.003505/0.003505, allocations: 357.5 kB / 16.59 MB, free: 5.566 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.553/1.553, allocations: 165.8 MB / 183.1 MB, free: 5.426 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.073/4.073, allocations: 410.7 MB / 0.6001 GB, free: 15.24 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.001744/0.001744, allocations: 194.8 kB / 0.6668 GB, free: 6.957 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004707/0.0004709, allocations: 12 kB / 0.7429 GB, free: 8.988 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4012/0.4017, allocations: 67.45 MB / 0.8087 GB, free: 28.8 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortAir): time 0.7058/1.108, allocations: 164.7 MB / 0.9696 GB, free: 1.227 MB / 474.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01189/1.12, allocations: 6.517 MB / 0.976 GB, free: 1.039 MB / 474.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001363/1.121, allocations: 23.28 kB / 0.976 GB, free: 1.027 MB / 474.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.00185/1.123, allocations: 395.2 kB / 0.9764 GB, free: 0.9219 MB / 474.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.00593/1.129, allocations: 1.457 MB / 0.9778 GB, free: 0.6016 MB / 474.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003923/1.133, allocations: 0.8606 MB / 0.9786 GB, free: 0.5469 MB / 474.7 MB
Notification: Performance of NFFlatten.flatten: time 0.009087/1.142, allocations: 4.66 MB / 0.9832 GB, free: 15.98 MB / 490.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002171/1.144, allocations: 1.071 MB / 0.9842 GB, free: 15.57 MB / 490.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003168/1.147, allocations: 1.109 MB / 0.9853 GB, free: 15.17 MB / 490.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002809/1.15, allocations: 1.117 MB / 0.9864 GB, free: 14.77 MB / 490.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003155/1.151, allocations: 52.09 kB / 0.9864 GB, free: 14.77 MB / 490.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003047/1.154, allocations: 0.6509 MB / 0.9871 GB, free: 14.59 MB / 490.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.00101/1.155, allocations: 482 kB / 0.9875 GB, free: 14.38 MB / 490.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0006999/1.155, allocations: 319 kB / 0.9878 GB, free: 14.3 MB / 490.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.003472/1.159, allocations: 1.954 MB / 0.9897 GB, free: 13.32 MB / 490.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.542e-06/1.159, allocations: 0 / 0.9897 GB, free: 13.32 MB / 490.7 MB
Notification: Performance of FrontEnd: time 5.069e-06/1.159, allocations: 6.391 kB / 0.9898 GB, free: 13.32 MB / 490.7 MB
Notification: Performance of Transformations before backend: time 5.251e-05/1.159, allocations: 3.938 kB / 0.9898 GB, free: 13.31 MB / 490.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 247
 * Number of variables: 247
Notification: Performance of Generate backend data structure: time 0.00441/1.163, allocations: 1.597 MB / 0.9913 GB, free: 12.26 MB / 490.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.861e-05/1.163, allocations: 11.81 kB / 0.9913 GB, free: 12.25 MB / 490.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004908/1.168, allocations: 488.3 kB / 0.9918 GB, free: 12.07 MB / 490.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00338/1.172, allocations: 1.153 MB / 0.9929 GB, free: 11.53 MB / 490.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 7.695e-05/1.172, allocations: 76.12 kB / 0.993 GB, free: 11.53 MB / 490.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002736/1.172, allocations: 110.5 kB / 0.9931 GB, free: 11.52 MB / 490.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003542/1.176, allocations: 1.334 MB / 0.9944 GB, free: 11.15 MB / 490.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.892e-05/1.176, allocations: 2.844 kB / 0.9944 GB, free: 11.15 MB / 490.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001647/1.176, allocations: 39.34 kB / 0.9944 GB, free: 11.15 MB / 490.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.355e-05/1.176, allocations: 44.39 kB / 0.9945 GB, free: 11.14 MB / 490.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003277/1.179, allocations: 1.268 MB / 0.9957 GB, free: 10.88 MB / 490.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.01042/1.19, allocations: 4.919 MB / 1.001 GB, free: 7.812 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002132/1.192, allocations: 1.001 MB / 1.002 GB, free: 7.605 MB / 490.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001182/1.193, allocations: 0.5773 MB / 1.002 GB, free: 7.488 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004665/1.194, allocations: 205.1 kB / 1.002 GB, free: 7.289 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.491e-05/1.194, allocations: 41.12 kB / 1.002 GB, free: 7.25 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=78): time 3.858e-06/1.194, allocations: 0 / 1.002 GB, free: 7.25 MB / 490.7 MB
Notification: Performance of matching and sorting (n=81): time 0.008935/1.203, allocations: 2.98 MB / 1.005 GB, free: 6.324 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.719e-05/1.203, allocations: 61 kB / 1.005 GB, free: 6.262 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001838/1.205, allocations: 0.9594 MB / 1.006 GB, free: 5.938 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002403/1.205, allocations: 42.33 kB / 1.006 GB, free: 5.906 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0005293/1.206, allocations: 0.5343 MB / 1.007 GB, free: 5.637 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004766/1.206, allocations: 293.7 kB / 1.007 GB, free: 5.43 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 6.991e-05/1.206, allocations: 14.75 kB / 1.007 GB, free: 5.43 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001477/1.206, allocations: 338.1 kB / 1.007 GB, free: 5.117 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.000981/1.207, allocations: 437.7 kB / 1.008 GB, free: 5.027 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001182/1.208, allocations: 0.6033 MB / 1.008 GB, free: 4.711 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001309/1.21, allocations: 0.5475 MB / 1.009 GB, free: 4.336 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.608e-06/1.21, allocations: 0 / 1.009 GB, free: 4.336 MB / 490.7 MB
Notification: Performance of matching and sorting (n=97) (initialization): time 0.003239/1.213, allocations: 1.24 MB / 1.01 GB, free: 3.391 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001407/1.213, allocations: 171.4 kB / 1.01 GB, free: 3.199 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.364e-05/1.213, allocations: 4 kB / 1.01 GB, free: 3.195 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0003863/1.214, allocations: 68.31 kB / 1.01 GB, free: 3.137 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002051/1.216, allocations: 218.1 kB / 1.011 GB, free: 2.93 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003381/1.219, allocations: 2.98 MB / 1.013 GB, free: 4 kB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002643/1.222, allocations: 139.7 kB / 1.014 GB, free: 15.88 MB / 0.4948 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001612/1.222, allocations: 36.23 kB / 1.014 GB, free: 15.86 MB / 0.4948 GB
Notification: Performance of prepare postOptimizeDAE: time 1.295e-05/1.222, allocations: 8 kB / 1.014 GB, free: 15.85 MB / 0.4948 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.00289/1.225, allocations: 1.111 MB / 1.015 GB, free: 15.05 MB / 0.4948 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.133e-05/1.225, allocations: 5.859 kB / 1.015 GB, free: 15.05 MB / 0.4948 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.000337/1.225, allocations: 77.98 kB / 1.015 GB, free: 14.98 MB / 0.4948 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001482/1.227, allocations: 180.8 kB / 1.015 GB, free: 14.81 MB / 0.4948 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00243/1.229, allocations: 1.812 MB / 1.017 GB, free: 13.09 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002586/1.232, allocations: 134.8 kB / 1.017 GB, free: 12.97 MB / 0.4948 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000116/1.232, allocations: 34 kB / 1.017 GB, free: 12.95 MB / 0.4948 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 12
 * 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 (92):
 * Single equations (assignments): 89
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 2
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 0
 * Non-linear Jacobian: 2 {1,1}
 * Without analytic Jacobian: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(1,100.0%) 5}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.001276/1.233, allocations: 0.6025 MB / 1.018 GB, free: 12.45 MB / 0.4948 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004128/1.234, allocations: 106.8 kB / 1.018 GB, free: 12.35 MB / 0.4948 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003027/1.237, allocations: 1.182 MB / 1.019 GB, free: 11.51 MB / 0.4948 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002765/1.24, allocations: 1.238 MB / 1.02 GB, free: 10.48 MB / 0.4948 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.401e-05/1.24, allocations: 0 / 1.02 GB, free: 10.48 MB / 0.4948 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002127/1.242, allocations: 324.1 kB / 1.02 GB, free: 10.2 MB / 0.4948 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003321/1.242, allocations: 20.61 kB / 1.02 GB, free: 10.18 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002137/1.244, allocations: 116.2 kB / 1.02 GB, free: 10.08 MB / 0.4948 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001711/1.245, allocations: 50.73 kB / 1.02 GB, free: 10.04 MB / 0.4948 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.004793/1.249, allocations: 1.407 MB / 1.022 GB, free: 8.938 MB / 0.4948 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.004262/1.254, allocations: 1.954 MB / 1.024 GB, free: 7.457 MB / 0.4948 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00112/1.255, allocations: 0.5094 MB / 1.024 GB, free: 7.098 MB / 0.4948 GB
Notification: Performance of sorting global known variables: time 0.001402/1.256, allocations: 0.6759 MB / 1.025 GB, free: 6.562 MB / 0.4948 GB
Notification: Performance of Backend: time 5.41e-07/1.256, allocations: 0 / 1.025 GB, free: 6.562 MB / 0.4948 GB
Notification: Performance of simCode: created initialization part: time 0.006274/1.263, allocations: 1.815 MB / 1.027 GB, free: 5.094 MB / 0.4948 GB
Notification: Performance of SimCode: time 0.01148/1.274, allocations: 4.215 MB / 1.031 GB, free: 1.109 MB / 0.4948 GB
Notification: Performance of Templates: time 0.07809/1.352, allocations: 29.71 MB / 1.06 GB, free: 3.66 MB / 0.5261 GB